builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-031 starttime: 1487577209.52 results: success (0) revision: c3cbadc5d2fa0adde48cbe48ed46cd0a5f7ec2bb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-19 23:53:29.524509) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-19 23:53:29.524956) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-19 23:53:29.525231) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-19 23:53:29.542142) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-19 23:53:29.542495) ========= 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=db0483c52d987ecb441a263b000001ad-1487576936.359303-1904231953 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-19 23:53:29-- 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% 124M=0s 2017-02-19 23:53:29 (124 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.284577 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-19 23:53:29.841489) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-19 23:53:29.841795) ========= 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=db0483c52d987ecb441a263b000001ad-1487576936.359303-1904231953 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021111 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-19 23:53:29.892841) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-19 23:53:29.893348) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c3cbadc5d2fa0adde48cbe48ed46cd0a5f7ec2bb --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 c3cbadc5d2fa0adde48cbe48ed46cd0a5f7ec2bb --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=db0483c52d987ecb441a263b000001ad-1487576936.359303-1904231953 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-19 23:53:30,010 truncating revision to first 12 chars 2017-02-19 23:53:30,010 Setting DEBUG logging. 2017-02-19 23:53:30,010 attempt 1/10 2017-02-19 23:53:30,010 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c3cbadc5d2fa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-19 23:53:31,213 unpacking tar archive at: mozilla-central-c3cbadc5d2fa/testing/mozharness/ program finished with exit code 0 elapsedTime=1.533355 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-19 23:53:31.440511) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-19 23:53:31.440815) ========= script_repo_revision: c3cbadc5d2fa0adde48cbe48ed46cd0a5f7ec2bb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-19 23:53:31.441221) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-19 23:53:31.441501) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-19 23:53:31.450165) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-19 23:53:31.450485) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db0483c52d987ecb441a263b000001ad-1487576936.359303-1904231953 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:53:31 INFO - MultiFileLogger online at 20170219 23:53:31 in /builds/slave/test 23:53:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 23:53:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:53:31 INFO - {'append_to_log': False, 23:53:31 INFO - 'base_work_dir': '/builds/slave/test', 23:53:31 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 23:53:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:53:31 INFO - 'branch': 'Firefox-Non-PGO', 23:53:31 INFO - 'buildbot_json_path': 'buildprops.json', 23:53:31 INFO - 'config_files': ('talos/linux_config.py',), 23:53:31 INFO - 'default_actions': ('clobber', 23:53:31 INFO - 'read-buildbot-config', 23:53:31 INFO - 'download-and-extract', 23:53:31 INFO - 'populate-webroot', 23:53:31 INFO - 'create-virtualenv', 23:53:31 INFO - 'install', 23:53:31 INFO - 'run-tests'), 23:53:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:53:31 INFO - 'download_minidump_stackwalk': True, 23:53:31 INFO - 'download_symbols': 'ondemand', 23:53:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:53:31 INFO - 'tooltool.py': '/tools/tooltool.py', 23:53:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:53:31 INFO - '/tools/misc-python/virtualenv.py')}, 23:53:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:53:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:53:31 INFO - 'installer_path': 'installer.exe', 23:53:31 INFO - 'log_level': 'info', 23:53:31 INFO - 'log_name': 'talos', 23:53:31 INFO - 'log_to_console': True, 23:53:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:53:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:53:31 INFO - 'opt_config_files': (), 23:53:31 INFO - 'pip_index': False, 23:53:31 INFO - 'sps_profile': False, 23:53:31 INFO - 'sps_profile_interval': 0, 23:53:31 INFO - 'suite': 'dromaeojs-e10s', 23:53:31 INFO - 'system_bits': '32', 23:53:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:53:31 INFO - 'title': 'talos-linux64-ix-031', 23:53:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:53:31 INFO - 'use_talos_json': True, 23:53:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:53:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:53:31 INFO - 'work_dir': 'build'} 23:53:31 INFO - [mozharness: 2017-02-20 07:53:31.667425Z] Running clobber step. 23:53:31 INFO - Running pre-action listener: _resource_record_pre_action 23:53:31 INFO - Running main action method: clobber 23:53:31 INFO - rmtree: /builds/slave/test/build 23:53:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:53:32 INFO - Running post-action listener: _resource_record_post_action 23:53:32 INFO - [mozharness: 2017-02-20 07:53:32.580790Z] Finished clobber step (success) 23:53:32 INFO - [mozharness: 2017-02-20 07:53:32.580888Z] Running read-buildbot-config step. 23:53:32 INFO - Running pre-action listener: _resource_record_pre_action 23:53:32 INFO - Running main action method: read_buildbot_config 23:53:32 INFO - Using buildbot properties: 23:53:32 INFO - { 23:53:32 INFO - "project": "", 23:53:32 INFO - "product": "firefox", 23:53:32 INFO - "who": "ihsiao@mozilla.com", 23:53:32 INFO - "installer_path": "public/build/target.tar.bz2", 23:53:32 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 23:53:32 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 23:53:32 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 23:53:32 INFO - "stage_platform": "linux64", 23:53:32 INFO - "basedir": "/builds/slave/test", 23:53:32 INFO - "buildnumber": 705, 23:53:32 INFO - "platform": "ubuntu64_hw", 23:53:32 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 23:53:32 INFO - "slavebuilddir": "test", 23:53:32 INFO - "taskId": "F0N7DdvWSEKGIYV9MXfA4g", 23:53:32 INFO - "branch": "mozilla-central", 23:53:32 INFO - "script_repo_revision": "production", 23:53:32 INFO - "revision": "c3cbadc5d2fa0adde48cbe48ed46cd0a5f7ec2bb", 23:53:32 INFO - "slavename": "talos-linux64-ix-031", 23:53:32 INFO - "repo_path": "mozilla-central" 23:53:32 INFO - } 23:53:32 INFO - Finding installer, test and symbols from parent task. 23:53:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F0N7DdvWSEKGIYV9MXfA4g'}, attempt #1 23:53:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F0N7DdvWSEKGIYV9MXfA4g'}, attempt #1 23:53:33 INFO - Task dependencies: BYB1M964StmeU_0sm8ursg 23:53:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BYB1M964StmeU_0sm8ursg'}, attempt #1 23:53:33 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/BYB1M964StmeU_0sm8ursg/artifacts/public/build/target.tar.bz2 23:53:33 INFO - Set test_url: https://queue.taskcluster.net/v1/task/BYB1M964StmeU_0sm8ursg/artifacts/public/build/test_packages.json 23:53:33 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/BYB1M964StmeU_0sm8ursg/artifacts/public/build/target.crashreporter-symbols.zip 23:53:33 INFO - Running post-action listener: _resource_record_post_action 23:53:33 INFO - [mozharness: 2017-02-20 07:53:33.968960Z] Finished read-buildbot-config step (success) 23:53:33 INFO - [mozharness: 2017-02-20 07:53:33.969177Z] Running download-and-extract step. 23:53:33 INFO - Running pre-action listener: _resource_record_pre_action 23:53:33 INFO - Running main action method: download_and_extract 23:53:33 INFO - mkdir: /builds/slave/test/build/tests 23:53:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:53:33 INFO - trying https://queue.taskcluster.net/v1/task/BYB1M964StmeU_0sm8ursg/artifacts/public/build/target.test_packages.json 23:53:33 INFO - Downloading https://queue.taskcluster.net/v1/task/BYB1M964StmeU_0sm8ursg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 23:53:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BYB1M964StmeU_0sm8ursg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 23:53:34 INFO - Downloaded 896 bytes. 23:53:34 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 23:53:34 INFO - Using the following test package requirements: 23:53:34 INFO - {u'common': [u'target.common.tests.zip'], 23:53:34 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 23:53:34 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 23:53:34 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 23:53:34 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 23:53:34 INFO - u'mozbase': [u'target.common.tests.zip'], 23:53:34 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 23:53:34 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 23:53:34 INFO - u'web-platform': [u'target.common.tests.zip', 23:53:34 INFO - u'target.web-platform.tests.zip'], 23:53:34 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 23:53:34 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 23:53:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BYB1M964StmeU_0sm8ursg/artifacts/public/build/target.common.tests.zip 23:53:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BYB1M964StmeU_0sm8ursg/artifacts/public/build/target.common.tests.zip'}, attempt #1 23:53:34 INFO - Fetch https://queue.taskcluster.net/v1/task/BYB1M964StmeU_0sm8ursg/artifacts/public/build/target.common.tests.zip into memory 23:53:35 INFO - Expected file size: 57145638 23:53:35 INFO - Obtained file size: 57145638 23:53:45 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 23:53:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BYB1M964StmeU_0sm8ursg/artifacts/public/build/target.common.tests.zip 23:53:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BYB1M964StmeU_0sm8ursg/artifacts/public/build/target.common.tests.zip'}, attempt #1 23:53:45 INFO - Fetch https://queue.taskcluster.net/v1/task/BYB1M964StmeU_0sm8ursg/artifacts/public/build/target.common.tests.zip into memory 23:53:46 INFO - Expected file size: 57145638 23:53:46 INFO - Obtained file size: 57145638 23:53:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BYB1M964StmeU_0sm8ursg/artifacts/public/build/target.talos.tests.zip 23:53:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BYB1M964StmeU_0sm8ursg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 23:53:57 INFO - Fetch https://queue.taskcluster.net/v1/task/BYB1M964StmeU_0sm8ursg/artifacts/public/build/target.talos.tests.zip into memory 23:53:58 INFO - Expected file size: 14403647 23:53:58 INFO - Obtained file size: 14403647 23:53:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:53:58 INFO - trying https://queue.taskcluster.net/v1/task/BYB1M964StmeU_0sm8ursg/artifacts/public/build/target.tar.bz2 23:53:58 INFO - Downloading https://queue.taskcluster.net/v1/task/BYB1M964StmeU_0sm8ursg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 23:53:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BYB1M964StmeU_0sm8ursg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 23:54:01 INFO - Downloaded 61462735 bytes. 23:54:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BYB1M964StmeU_0sm8ursg/artifacts/public/build/target.tar.bz2 23:54:01 INFO - mkdir: /builds/slave/test/properties 23:54:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:54:01 INFO - Writing to file /builds/slave/test/properties/build_url 23:54:01 INFO - Contents: 23:54:01 INFO - build_url:https://queue.taskcluster.net/v1/task/BYB1M964StmeU_0sm8ursg/artifacts/public/build/target.tar.bz2 23:54:01 INFO - Running post-action listener: _resource_record_post_action 23:54:01 INFO - Running post-action listener: set_extra_try_arguments 23:54:01 INFO - [mozharness: 2017-02-20 07:54:01.003099Z] Finished download-and-extract step (success) 23:54:01 INFO - [mozharness: 2017-02-20 07:54:01.003197Z] Running populate-webroot step. 23:54:01 INFO - Running pre-action listener: _resource_record_pre_action 23:54:01 INFO - Running main action method: populate_webroot 23:54:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:54:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:54:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:54:01 INFO - '--tppagecycles', 23:54:01 INFO - '7'], 23:54:01 INFO - 'tests': ['tsvgm']}}, 23:54:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:54:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:54:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:54:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:54:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:54:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:54:01 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 23:54:01 INFO - 'talos_options': ['--disable-e10s'], 23:54:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:54:01 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 23:54:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:54:01 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 23:54:01 INFO - 'talos_options': ['--disable-e10s'], 23:54:01 INFO - 'tests': ['damp', 'tps']}, 23:54:01 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 23:54:01 INFO - 'tests': ['damp', 'tps']}, 23:54:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:54:01 INFO - 'tests': ['dromaeo_dom']}, 23:54:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:54:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:54:01 INFO - 'tests': ['basic_compositor_video']}, 23:54:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:54:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:54:01 INFO - 'tests': ['a11yr', 23:54:01 INFO - 'ts_paint', 23:54:01 INFO - 'tpaint', 23:54:01 INFO - 'sessionrestore', 23:54:01 INFO - 'sessionrestore_no_auto_restore', 23:54:01 INFO - 'tabpaint']}, 23:54:01 INFO - 'other-e10s': {'tests': ['a11yr', 23:54:01 INFO - 'ts_paint', 23:54:01 INFO - 'tpaint', 23:54:01 INFO - 'sessionrestore', 23:54:01 INFO - 'sessionrestore_no_auto_restore', 23:54:01 INFO - 'tabpaint']}, 23:54:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:54:01 INFO - 'ts_paint', 23:54:01 INFO - 'tpaint', 23:54:01 INFO - 'sessionrestore', 23:54:01 INFO - 'sessionrestore_no_auto_restore', 23:54:01 INFO - 'tabpaint']}, 23:54:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:54:01 INFO - 'ts_paint', 23:54:01 INFO - 'tpaint', 23:54:01 INFO - 'sessionrestore', 23:54:01 INFO - 'sessionrestore_no_auto_restore', 23:54:01 INFO - 'tabpaint']}, 23:54:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:54:01 INFO - 'tests': ['a11yr', 23:54:01 INFO - 'ts_paint', 23:54:01 INFO - 'tpaint', 23:54:01 INFO - 'sessionrestore', 23:54:01 INFO - 'sessionrestore_no_auto_restore', 23:54:01 INFO - 'tabpaint']}, 23:54:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:54:01 INFO - 'tests': ['a11yr', 23:54:01 INFO - 'ts_paint', 23:54:01 INFO - 'tpaint', 23:54:01 INFO - 'sessionrestore', 23:54:01 INFO - 'sessionrestore_no_auto_restore', 23:54:01 INFO - 'tabpaint']}, 23:54:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:54:01 INFO - 'tests': ['tsvgx', 23:54:01 INFO - 'tsvgr_opacity', 23:54:01 INFO - 'tart', 23:54:01 INFO - 'tscrollx', 23:54:01 INFO - 'cart', 23:54:01 INFO - 'tsvg_static']}, 23:54:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:54:01 INFO - 'tsvgr_opacity', 23:54:01 INFO - 'tart', 23:54:01 INFO - 'tscrollx', 23:54:01 INFO - 'cart', 23:54:01 INFO - 'tsvg_static']}, 23:54:01 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 23:54:01 INFO - 'talos_options': ['--disable-e10s'], 23:54:01 INFO - 'tests': ['tp5o']}, 23:54:01 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 23:54:01 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 23:54:01 INFO - 'talos_options': ['--disable-e10s', 23:54:01 INFO - '--xperf_path', 23:54:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:54:01 INFO - 'tests': ['tp5n']}, 23:54:01 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 23:54:01 INFO - 'talos_options': ['--xperf_path', 23:54:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:54:01 INFO - 'tests': ['tp5n']}}, 23:54:01 INFO - 'talos.zip': {'path': '', 23:54:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:54:01 INFO - Running post-action listener: _resource_record_post_action 23:54:01 INFO - [mozharness: 2017-02-20 07:54:01.016337Z] Finished populate-webroot step (success) 23:54:01 INFO - [mozharness: 2017-02-20 07:54:01.016427Z] Running create-virtualenv step. 23:54:01 INFO - Running pre-action listener: _resource_record_pre_action 23:54:01 INFO - Running main action method: create_virtualenv 23:54:01 INFO - Creating virtualenv /builds/slave/test/build/venv 23:54:01 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 23:54:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:54:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:54:01 INFO - Using real prefix '/usr' 23:54:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:54:01 INFO - Installing distribute.............................................................................................................................................................................................done. 23:54:03 INFO - Installing pip.................done. 23:54:03 INFO - Return code: 0 23:54:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 23:54:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 23:54:03 INFO - Reading from file tmpfile_stdout 23:54:03 INFO - Output received: 23:54:03 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) 23:54:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:54:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:54:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:54:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:54:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:54:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:54:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:54:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c2b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c0290>, '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 0x13c0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c1040>, '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': 'db0483c52d987ecb441a263b000001ad-1487576936.359303-1904231953', '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 23:54:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:54:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:54:03 INFO - Using env: {'DISPLAY': ':0', 23:54:03 INFO - 'HOME': '/home/cltbld', 23:54:03 INFO - 'LANG': 'en_US.UTF-8', 23:54:03 INFO - 'LANGUAGE': 'en_US:en', 23:54:03 INFO - 'LOGNAME': 'cltbld', 23:54:03 INFO - 'MAIL': '/var/mail/cltbld', 23:54:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:54:03 INFO - 'MOZ_NO_REMOTE': '1', 23:54:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:54:03 INFO - 'NO_EM_RESTART': '1', 23:54:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:54:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:54:03 INFO - 'PWD': '/builds/slave/test', 23:54:03 INFO - 'SHELL': '/bin/bash', 23:54:03 INFO - 'SHLVL': '1', 23:54:03 INFO - 'TERM': 'linux', 23:54:03 INFO - 'TMOUT': '86400', 23:54:03 INFO - 'USER': 'cltbld', 23:54:03 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1487576936.359303-1904231953', 23:54:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:54:03 INFO - '_': '/tools/buildbot/bin/python'} 23:54:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:54:03 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 23:54:03 INFO - Cleaning up... 23:54:04 INFO - Return code: 0 23:54:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:54:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:54:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:54:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:54:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:54:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:54:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c2b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c0290>, '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 0x13c0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c1040>, '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': 'db0483c52d987ecb441a263b000001ad-1487576936.359303-1904231953', '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 23:54:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:54:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:54:04 INFO - Using env: {'DISPLAY': ':0', 23:54:04 INFO - 'HOME': '/home/cltbld', 23:54:04 INFO - 'LANG': 'en_US.UTF-8', 23:54:04 INFO - 'LANGUAGE': 'en_US:en', 23:54:04 INFO - 'LOGNAME': 'cltbld', 23:54:04 INFO - 'MAIL': '/var/mail/cltbld', 23:54:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:54:04 INFO - 'MOZ_NO_REMOTE': '1', 23:54:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:54:04 INFO - 'NO_EM_RESTART': '1', 23:54:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:54:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:54:04 INFO - 'PWD': '/builds/slave/test', 23:54:04 INFO - 'SHELL': '/bin/bash', 23:54:04 INFO - 'SHLVL': '1', 23:54:04 INFO - 'TERM': 'linux', 23:54:04 INFO - 'TMOUT': '86400', 23:54:04 INFO - 'USER': 'cltbld', 23:54:04 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1487576936.359303-1904231953', 23:54:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:54:04 INFO - '_': '/tools/buildbot/bin/python'} 23:54:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:54:04 INFO - Downloading/unpacking psutil>=3.1.1 23:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:54:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:54:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:54:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:54:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:54:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:54:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:54:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:54:07 INFO - Installing collected packages: psutil 23:54:07 INFO - Running setup.py install for psutil 23:54:07 INFO - building 'psutil._psutil_linux' extension 23:54:07 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 23:54:07 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 23:54:07 INFO - building 'psutil._psutil_posix' extension 23:54:07 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 23:54:07 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 23:54:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:54:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:54:07 INFO - Successfully installed psutil 23:54:07 INFO - Cleaning up... 23:54:07 INFO - Return code: 0 23:54:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:54:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:54:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:54:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:54:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:54:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:54:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c2b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c0290>, '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 0x13c0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c1040>, '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': 'db0483c52d987ecb441a263b000001ad-1487576936.359303-1904231953', '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 23:54:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:54:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 23:54:07 INFO - Using env: {'DISPLAY': ':0', 23:54:07 INFO - 'HOME': '/home/cltbld', 23:54:07 INFO - 'LANG': 'en_US.UTF-8', 23:54:07 INFO - 'LANGUAGE': 'en_US:en', 23:54:07 INFO - 'LOGNAME': 'cltbld', 23:54:07 INFO - 'MAIL': '/var/mail/cltbld', 23:54:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:54:07 INFO - 'MOZ_NO_REMOTE': '1', 23:54:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:54:07 INFO - 'NO_EM_RESTART': '1', 23:54:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:54:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:54:07 INFO - 'PWD': '/builds/slave/test', 23:54:07 INFO - 'SHELL': '/bin/bash', 23:54:07 INFO - 'SHLVL': '1', 23:54:07 INFO - 'TERM': 'linux', 23:54:07 INFO - 'TMOUT': '86400', 23:54:07 INFO - 'USER': 'cltbld', 23:54:07 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1487576936.359303-1904231953', 23:54:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:54:07 INFO - '_': '/tools/buildbot/bin/python'} 23:54:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:54:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:54:08 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 23:54:08 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 23:54:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:54:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:54:08 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 23:54:08 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 23:54:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:54:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:54:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:54:10 INFO - Installing collected packages: mozsystemmonitor 23:54:10 INFO - Running setup.py install for mozsystemmonitor 23:54:10 INFO - Successfully installed mozsystemmonitor 23:54:10 INFO - Cleaning up... 23:54:10 INFO - Return code: 0 23:54:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:54:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:54:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:54:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:54:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:54:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:54:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:54:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c2b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c0290>, '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 0x13c0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c1040>, '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': 'db0483c52d987ecb441a263b000001ad-1487576936.359303-1904231953', '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 23:54:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:54:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:54:10 INFO - Using env: {'DISPLAY': ':0', 23:54:10 INFO - 'HOME': '/home/cltbld', 23:54:10 INFO - 'LANG': 'en_US.UTF-8', 23:54:10 INFO - 'LANGUAGE': 'en_US:en', 23:54:10 INFO - 'LOGNAME': 'cltbld', 23:54:10 INFO - 'MAIL': '/var/mail/cltbld', 23:54:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:54:10 INFO - 'MOZ_NO_REMOTE': '1', 23:54:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:54:10 INFO - 'NO_EM_RESTART': '1', 23:54:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:54:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:54:10 INFO - 'PWD': '/builds/slave/test', 23:54:10 INFO - 'SHELL': '/bin/bash', 23:54:10 INFO - 'SHLVL': '1', 23:54:10 INFO - 'TERM': 'linux', 23:54:10 INFO - 'TMOUT': '86400', 23:54:10 INFO - 'USER': 'cltbld', 23:54:10 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1487576936.359303-1904231953', 23:54:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:54:10 INFO - '_': '/tools/buildbot/bin/python'} 23:54:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:54:10 INFO - Downloading/unpacking jsonschema==2.5.1 23:54:10 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 23:54:10 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 23:54:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:54:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:54:10 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 23:54:10 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 23:54:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:54:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:54:12 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 23:54:12 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 23:54:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:54:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:54:12 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 23:54:12 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 23:54:12 INFO - Downloading functools32-3.2.3-2.tar.gz 23:54:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:54:12 INFO - warning: no files found matching '*.txt' 23:54:12 INFO - no previously-included directories found matching 'build' 23:54:12 INFO - no previously-included directories found matching 'dist' 23:54:12 INFO - no previously-included directories found matching '.git*' 23:54:12 INFO - Installing collected packages: jsonschema, functools32 23:54:12 INFO - Running setup.py install for functools32 23:54:12 INFO - warning: no files found matching '*.txt' 23:54:12 INFO - no previously-included directories found matching 'build' 23:54:12 INFO - no previously-included directories found matching 'dist' 23:54:12 INFO - no previously-included directories found matching '.git*' 23:54:13 INFO - Successfully installed jsonschema functools32 23:54:13 INFO - Cleaning up... 23:54:13 INFO - Return code: 0 23:54:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:54:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:54:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:54:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:54:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:54:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:54:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:54:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c2b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c0290>, '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 0x13c0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c1040>, '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': 'db0483c52d987ecb441a263b000001ad-1487576936.359303-1904231953', '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 23:54:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 23:54:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 23:54:13 INFO - Using env: {'DISPLAY': ':0', 23:54:13 INFO - 'HOME': '/home/cltbld', 23:54:13 INFO - 'LANG': 'en_US.UTF-8', 23:54:13 INFO - 'LANGUAGE': 'en_US:en', 23:54:13 INFO - 'LOGNAME': 'cltbld', 23:54:13 INFO - 'MAIL': '/var/mail/cltbld', 23:54:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:54:13 INFO - 'MOZ_NO_REMOTE': '1', 23:54:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:54:13 INFO - 'NO_EM_RESTART': '1', 23:54:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:54:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:54:13 INFO - 'PWD': '/builds/slave/test', 23:54:13 INFO - 'SHELL': '/bin/bash', 23:54:13 INFO - 'SHLVL': '1', 23:54:13 INFO - 'TERM': 'linux', 23:54:13 INFO - 'TMOUT': '86400', 23:54:13 INFO - 'USER': 'cltbld', 23:54:13 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1487576936.359303-1904231953', 23:54:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:54:13 INFO - '_': '/tools/buildbot/bin/python'} 23:54:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:54:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:54:13 INFO - Cleaning up... 23:54:13 INFO - Return code: 0 23:54:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:54:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:54:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:54:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:54:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:54:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:54:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:54:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c2b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c0290>, '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 0x13c0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c1040>, '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': 'db0483c52d987ecb441a263b000001ad-1487576936.359303-1904231953', '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 23:54:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:54:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:54:13 INFO - Using env: {'DISPLAY': ':0', 23:54:13 INFO - 'HOME': '/home/cltbld', 23:54:13 INFO - 'LANG': 'en_US.UTF-8', 23:54:13 INFO - 'LANGUAGE': 'en_US:en', 23:54:13 INFO - 'LOGNAME': 'cltbld', 23:54:13 INFO - 'MAIL': '/var/mail/cltbld', 23:54:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:54:13 INFO - 'MOZ_NO_REMOTE': '1', 23:54:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:54:13 INFO - 'NO_EM_RESTART': '1', 23:54:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:54:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:54:13 INFO - 'PWD': '/builds/slave/test', 23:54:13 INFO - 'SHELL': '/bin/bash', 23:54:13 INFO - 'SHLVL': '1', 23:54:13 INFO - 'TERM': 'linux', 23:54:13 INFO - 'TMOUT': '86400', 23:54:13 INFO - 'USER': 'cltbld', 23:54:13 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1487576936.359303-1904231953', 23:54:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:54:13 INFO - '_': '/tools/buildbot/bin/python'} 23:54:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:54:13 INFO - Downloading/unpacking blobuploader==1.2.4 23:54:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:54:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:54:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:54:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:54:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:54:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:54:15 INFO - Downloading blobuploader-1.2.4.tar.gz 23:54:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:54:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:54:15 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 23:54:15 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 23:54:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:54:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:54:15 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 23:54:15 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 23:54:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:54:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:54:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:54:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:54:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:54:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:54:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:54:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:54:16 INFO - Downloading docopt-0.6.1.tar.gz 23:54:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:54:16 INFO - Installing collected packages: blobuploader, requests, docopt 23:54:16 INFO - Running setup.py install for blobuploader 23:54:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:54:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:54:16 INFO - Running setup.py install for requests 23:54:16 INFO - Running setup.py install for docopt 23:54:16 INFO - Successfully installed blobuploader requests docopt 23:54:16 INFO - Cleaning up... 23:54:16 INFO - Return code: 0 23:54:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:54:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:54:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:54:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:54:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:54:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:54:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:54:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c2b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c0290>, '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 0x13c0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c1040>, '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': 'db0483c52d987ecb441a263b000001ad-1487576936.359303-1904231953', '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 23:54:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:54:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:54:16 INFO - Using env: {'DISPLAY': ':0', 23:54:16 INFO - 'HOME': '/home/cltbld', 23:54:16 INFO - 'LANG': 'en_US.UTF-8', 23:54:16 INFO - 'LANGUAGE': 'en_US:en', 23:54:16 INFO - 'LOGNAME': 'cltbld', 23:54:16 INFO - 'MAIL': '/var/mail/cltbld', 23:54:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:54:16 INFO - 'MOZ_NO_REMOTE': '1', 23:54:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:54:16 INFO - 'NO_EM_RESTART': '1', 23:54:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:54:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:54:16 INFO - 'PWD': '/builds/slave/test', 23:54:16 INFO - 'SHELL': '/bin/bash', 23:54:16 INFO - 'SHLVL': '1', 23:54:16 INFO - 'TERM': 'linux', 23:54:16 INFO - 'TMOUT': '86400', 23:54:16 INFO - 'USER': 'cltbld', 23:54:16 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1487576936.359303-1904231953', 23:54:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:54:16 INFO - '_': '/tools/buildbot/bin/python'} 23:54:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:54:17 INFO - Running setup.py (path:/tmp/pip-qHm6Pp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:54:17 INFO - Running setup.py (path:/tmp/pip-39MjxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:54:17 INFO - Running setup.py (path:/tmp/pip-4VhUCZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:54:17 INFO - Running setup.py (path:/tmp/pip-JfGu9t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:54:17 INFO - Running setup.py (path:/tmp/pip-EkrOEM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:54:17 INFO - Running setup.py (path:/tmp/pip-uDsDdI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:54:17 INFO - Running setup.py (path:/tmp/pip-kktpzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:54:17 INFO - Running setup.py (path:/tmp/pip-SWYykW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:54:17 INFO - Running setup.py (path:/tmp/pip-rXiOsW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:54:17 INFO - Running setup.py (path:/tmp/pip-VIZlyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:54:17 INFO - Running setup.py (path:/tmp/pip-uuKniB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:54:17 INFO - Running setup.py (path:/tmp/pip-BDiAtD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:54:18 INFO - Running setup.py (path:/tmp/pip-oRH07_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:54:18 INFO - Running setup.py (path:/tmp/pip-B3PAj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:54:18 INFO - Running setup.py (path:/tmp/pip-7y4Ks3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:54:18 INFO - Running setup.py (path:/tmp/pip-oY8kgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:54:18 INFO - Running setup.py (path:/tmp/pip-RS4ldO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:54:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:54:18 INFO - Running setup.py install for manifestparser 23:54:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:54:18 INFO - Running setup.py install for mozcrash 23:54:18 INFO - Running setup.py install for mozdebug 23:54:18 INFO - Running setup.py install for mozdevice 23:54:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:54:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:54:18 INFO - Running setup.py install for mozfile 23:54:18 INFO - Running setup.py install for mozhttpd 23:54:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:54:19 INFO - Running setup.py install for mozinfo 23:54:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:54:19 INFO - Running setup.py install for mozInstall 23:54:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:54:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:54:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:54:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:54:19 INFO - Running setup.py install for mozleak 23:54:19 INFO - Running setup.py install for mozlog 23:54:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:54:19 INFO - Running setup.py install for moznetwork 23:54:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:54:19 INFO - Running setup.py install for mozprocess 23:54:19 INFO - Running setup.py install for mozprofile 23:54:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:54:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:54:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:54:19 INFO - Running setup.py install for mozrunner 23:54:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:54:19 INFO - Running setup.py install for mozscreenshot 23:54:19 INFO - Running setup.py install for moztest 23:54:20 INFO - Running setup.py install for mozversion 23:54:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:54:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:54:20 INFO - Cleaning up... 23:54:20 INFO - Return code: 0 23:54:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:54:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:54:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:54:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:54:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:54:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:54:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:54:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c2b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c0290>, '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 0x13c0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c1040>, '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': 'db0483c52d987ecb441a263b000001ad-1487576936.359303-1904231953', '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 23:54:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:54:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:54:20 INFO - Using env: {'DISPLAY': ':0', 23:54:20 INFO - 'HOME': '/home/cltbld', 23:54:20 INFO - 'LANG': 'en_US.UTF-8', 23:54:20 INFO - 'LANGUAGE': 'en_US:en', 23:54:20 INFO - 'LOGNAME': 'cltbld', 23:54:20 INFO - 'MAIL': '/var/mail/cltbld', 23:54:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:54:20 INFO - 'MOZ_NO_REMOTE': '1', 23:54:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:54:20 INFO - 'NO_EM_RESTART': '1', 23:54:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:54:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:54:20 INFO - 'PWD': '/builds/slave/test', 23:54:20 INFO - 'SHELL': '/bin/bash', 23:54:20 INFO - 'SHLVL': '1', 23:54:20 INFO - 'TERM': 'linux', 23:54:20 INFO - 'TMOUT': '86400', 23:54:20 INFO - 'USER': 'cltbld', 23:54:20 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1487576936.359303-1904231953', 23:54:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:54:20 INFO - '_': '/tools/buildbot/bin/python'} 23:54:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:54:20 INFO - Running setup.py (path:/tmp/pip-UNfG3r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:54:20 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)) 23:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:54:20 INFO - Running setup.py (path:/tmp/pip-u6p4gS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:54:20 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)) 23:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:54:20 INFO - Running setup.py (path:/tmp/pip-BBs6HA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:54:20 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)) 23:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:54:20 INFO - Running setup.py (path:/tmp/pip-TiWgEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:54:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:54:20 INFO - Running setup.py (path:/tmp/pip-zDRJV9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:54:20 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)) 23:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:54:20 INFO - Running setup.py (path:/tmp/pip-BdSJTq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:54:20 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)) 23:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:54:20 INFO - Running setup.py (path:/tmp/pip-m_BveZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:54:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 23:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:54:20 INFO - Running setup.py (path:/tmp/pip-kAJEU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:54:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 23:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:54:21 INFO - Running setup.py (path:/tmp/pip-DHBgDE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:54:21 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)) 23:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:54:21 INFO - Running setup.py (path:/tmp/pip-fv5pO7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:54:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:54:21 INFO - Running setup.py (path:/tmp/pip-Elujze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:54:21 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)) 23:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:54:21 INFO - Running setup.py (path:/tmp/pip-bdnco4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:54:21 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)) 23:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:54:21 INFO - Running setup.py (path:/tmp/pip-_aFl_y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:54:21 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)) 23:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:54:21 INFO - Running setup.py (path:/tmp/pip-OdiDBf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:54:21 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)) 23:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:54:21 INFO - Running setup.py (path:/tmp/pip-kPBFRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:54:21 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)) 23:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:54:21 INFO - Running setup.py (path:/tmp/pip-IQdaFy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:54:21 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)) 23:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:54:21 INFO - Running setup.py (path:/tmp/pip-H3fILT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:54:21 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)) 23:54:21 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)) 23:54:21 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)) 23:54:21 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)) 23:54:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:54:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:54:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:54:21 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 23:54:21 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 23:54:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:54:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:54:21 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 23:54:21 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 23:54:23 INFO - Downloading blessings-1.6.tar.gz 23:54:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:54:23 INFO - Installing collected packages: blessings 23:54:23 INFO - Running setup.py install for blessings 23:54:23 INFO - Successfully installed blessings 23:54:23 INFO - Cleaning up... 23:54:23 INFO - Return code: 0 23:54:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:54:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:54:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:54:24 INFO - Reading from file tmpfile_stdout 23:54:24 INFO - Current package versions: 23:54:24 INFO - argparse == 1.2.1 23:54:24 INFO - blessings == 1.6 23:54:24 INFO - blobuploader == 1.2.4 23:54:24 INFO - docopt == 0.6.1 23:54:24 INFO - functools32 == 3.2.3-2 23:54:24 INFO - jsonschema == 2.5.1 23:54:24 INFO - manifestparser == 1.1 23:54:24 INFO - mozInstall == 1.12 23:54:24 INFO - mozcrash == 1.0 23:54:24 INFO - mozdebug == 0.1 23:54:24 INFO - mozdevice == 0.49 23:54:24 INFO - mozfile == 1.2 23:54:24 INFO - mozhttpd == 0.7 23:54:24 INFO - mozinfo == 0.9 23:54:24 INFO - mozleak == 0.1 23:54:24 INFO - mozlog == 3.4 23:54:24 INFO - moznetwork == 0.27 23:54:24 INFO - mozprocess == 0.25 23:54:24 INFO - mozprofile == 0.28 23:54:24 INFO - mozrunner == 6.13 23:54:24 INFO - mozscreenshot == 0.1 23:54:24 INFO - mozsystemmonitor == 0.3 23:54:24 INFO - moztest == 0.8 23:54:24 INFO - mozversion == 1.4 23:54:24 INFO - psutil == 3.1.1 23:54:24 INFO - requests == 1.2.3 23:54:24 INFO - wsgiref == 0.1.2 23:54:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:54:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:54:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:54:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:54:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:54:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:54:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:54:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c2b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c0290>, '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 0x13c0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c1040>, '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': 'db0483c52d987ecb441a263b000001ad-1487576936.359303-1904231953', '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 23:54:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 23:54:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:54:24 INFO - Using env: {'DISPLAY': ':0', 23:54:24 INFO - 'HOME': '/home/cltbld', 23:54:24 INFO - 'LANG': 'en_US.UTF-8', 23:54:24 INFO - 'LANGUAGE': 'en_US:en', 23:54:24 INFO - 'LOGNAME': 'cltbld', 23:54:24 INFO - 'MAIL': '/var/mail/cltbld', 23:54:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:54:24 INFO - 'MOZ_NO_REMOTE': '1', 23:54:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:54:24 INFO - 'NO_EM_RESTART': '1', 23:54:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:54:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:54:24 INFO - 'PWD': '/builds/slave/test', 23:54:24 INFO - 'SHELL': '/bin/bash', 23:54:24 INFO - 'SHLVL': '1', 23:54:24 INFO - 'TERM': 'linux', 23:54:24 INFO - 'TMOUT': '86400', 23:54:24 INFO - 'USER': 'cltbld', 23:54:24 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1487576936.359303-1904231953', 23:54:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:54:24 INFO - '_': '/tools/buildbot/bin/python'} 23:54:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:54:24 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)) 23:54:24 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)) 23:54:24 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)) 23:54:24 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)) 23:54:24 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)) 23:54:24 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)) 23:54:24 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)) 23:54:24 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)) 23:54:24 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)) 23:54:24 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)) 23:54:24 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)) 23:54:24 INFO - Cleaning up... 23:54:24 INFO - Return code: 0 23:54:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:54:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:54:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:54:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:54:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:54:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:54:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:54:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x121da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c2b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c0290>, '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 0x13c0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c1040>, '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': 'db0483c52d987ecb441a263b000001ad-1487576936.359303-1904231953', '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 23:54:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:54:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:54:24 INFO - Using env: {'DISPLAY': ':0', 23:54:24 INFO - 'HOME': '/home/cltbld', 23:54:24 INFO - 'LANG': 'en_US.UTF-8', 23:54:24 INFO - 'LANGUAGE': 'en_US:en', 23:54:24 INFO - 'LOGNAME': 'cltbld', 23:54:24 INFO - 'MAIL': '/var/mail/cltbld', 23:54:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:54:24 INFO - 'MOZ_NO_REMOTE': '1', 23:54:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:54:24 INFO - 'NO_EM_RESTART': '1', 23:54:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:54:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:54:24 INFO - 'PWD': '/builds/slave/test', 23:54:24 INFO - 'SHELL': '/bin/bash', 23:54:24 INFO - 'SHLVL': '1', 23:54:24 INFO - 'TERM': 'linux', 23:54:24 INFO - 'TMOUT': '86400', 23:54:24 INFO - 'USER': 'cltbld', 23:54:24 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1487576936.359303-1904231953', 23:54:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:54:24 INFO - '_': '/tools/buildbot/bin/python'} 23:54:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:54:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:54:24 INFO - Cleaning up... 23:54:24 INFO - Return code: 0 23:54:24 INFO - Running post-action listener: _resource_record_post_action 23:54:24 INFO - Running post-action listener: _start_resource_monitoring 23:54:24 INFO - Starting resource monitoring. 23:54:24 INFO - [mozharness: 2017-02-20 07:54:24.671871Z] Finished create-virtualenv step (success) 23:54:24 INFO - [mozharness: 2017-02-20 07:54:24.672384Z] Running install step. 23:54:24 INFO - Running pre-action listener: _resource_record_pre_action 23:54:24 INFO - Running main action method: install 23:54:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:54:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:54:24 INFO - Reading from file tmpfile_stdout 23:54:24 INFO - Detecting whether we're running mozinstall >=1.0... 23:54:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:54:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:54:24 INFO - Reading from file tmpfile_stdout 23:54:24 INFO - Output received: 23:54:24 INFO - Usage: mozinstall [options] installer 23:54:24 INFO - Options: 23:54:24 INFO - -h, --help show this help message and exit 23:54:24 INFO - -d DEST, --destination=DEST 23:54:24 INFO - Directory to install application into. [default: 23:54:24 INFO - "/builds/slave/test"] 23:54:24 INFO - --app=APP Application being installed. [default: firefox] 23:54:24 INFO - mkdir: /builds/slave/test/build/application 23:54:24 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'] 23:54:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 23:54:40 INFO - Reading from file tmpfile_stdout 23:54:40 INFO - Output received: 23:54:40 INFO - /builds/slave/test/build/application/firefox/firefox 23:54:40 INFO - Running post-action listener: _resource_record_post_action 23:54:40 INFO - [mozharness: 2017-02-20 07:54:40.374692Z] Finished install step (success) 23:54:40 INFO - [mozharness: 2017-02-20 07:54:40.374968Z] Running run-tests step. 23:54:40 INFO - Running pre-action listener: _resource_record_pre_action 23:54:40 INFO - Running main action method: run_tests 23:54:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:54:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:54:40 INFO - Python 2.7.3 23:54:40 INFO - Return code: 0 23:54:40 INFO - grabbing minidump binary from tooltool 23:54:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:54:40 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 0x13c0290>, '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 0x13c0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c1040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:54:40 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 23:54:40 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 23:54:40 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 23:54:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:54:40 INFO - Return code: 0 23:54:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:54:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:54:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:54:40 INFO - ENV: RUST_BACKTRACE is now 1 23:54:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:54:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:54:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BYB1M964StmeU_0sm8ursg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 23:54:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BYB1M964StmeU_0sm8ursg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-031 --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 23:54:40 INFO - Using env: {'DISPLAY': ':0', 23:54:40 INFO - 'HOME': '/home/cltbld', 23:54:40 INFO - 'LANG': 'en_US.UTF-8', 23:54:40 INFO - 'LANGUAGE': 'en_US:en', 23:54:40 INFO - 'LOGNAME': 'cltbld', 23:54:40 INFO - 'MAIL': '/var/mail/cltbld', 23:54:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:54:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:54:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:54:40 INFO - 'MOZ_NO_REMOTE': '1', 23:54:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:54:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:54:40 INFO - 'NO_EM_RESTART': '1', 23:54:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:54:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:54:40 INFO - 'PWD': '/builds/slave/test', 23:54:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:54:40 INFO - 'RUST_BACKTRACE': '1', 23:54:40 INFO - 'SHELL': '/bin/bash', 23:54:40 INFO - 'SHLVL': '1', 23:54:40 INFO - 'TERM': 'linux', 23:54:40 INFO - 'TMOUT': '86400', 23:54:40 INFO - 'USER': 'cltbld', 23:54:40 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1487576936.359303-1904231953', 23:54:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:54:40 INFO - '_': '/tools/buildbot/bin/python'} 23:54:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BYB1M964StmeU_0sm8ursg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 23:54:40 INFO - mozversion application_buildid: 20170220072700 23:54:40 INFO - mozversion application_changeset: c3cbadc5d2fa0adde48cbe48ed46cd0a5f7ec2bb 23:54:40 INFO - mozversion application_display_name: Nightly 23:54:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:54:40 INFO - mozversion application_name: Firefox 23:54:40 INFO - mozversion application_remotingname: firefox 23:54:40 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 23:54:40 INFO - mozversion application_vendor: Mozilla 23:54:40 INFO - mozversion application_version: 54.0a1 23:54:40 INFO - mozversion platform_buildid: 20170220072700 23:54:40 INFO - mozversion platform_changeset: c3cbadc5d2fa0adde48cbe48ed46cd0a5f7ec2bb 23:54:40 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 23:54:40 INFO - mozversion platform_version: 54.0a1 23:54:40 INFO - using testdate: 1487577280 23:54:40 INFO - actual date: 1487577280 23:54:40 INFO - starting webserver on 'localhost:55505' 23:54:40 INFO - SUITE-START | Running 2 tests 23:54:40 INFO - TEST-START | dromaeo_css 23:54:40 INFO - operating with platform_type : linux_ 23:54:40 INFO - Initialising browser for dromaeo_css test... 23:54:40 INFO - TEST-INFO | started process 12162 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2UTfud/profile http://localhost:55505/getInfo.html) 23:54:43 INFO - PROCESS | 12162 | Iterating over profile /tmp/tmp2UTfud/profile 23:54:44 INFO - PROCESS | 12162 | No birth date. Using mtime. 23:54:44 INFO - PROCESS | 12162 | Using date: /tmp/tmp2UTfud/profile/pluginreg.dat = Sun Feb 19 2017 23:54:43 GMT-0800 (PST) 23:54:44 INFO - PROCESS | 12162 | No birth date. Using mtime. 23:54:44 INFO - PROCESS | 12162 | Using date: /tmp/tmp2UTfud/profile/user.js = Sun Feb 19 2017 23:54:40 GMT-0800 (PST) 23:54:44 INFO - PROCESS | 12162 | No birth date. Using mtime. 23:54:44 INFO - PROCESS | 12162 | Using date: /tmp/tmp2UTfud/profile/crashes = Sun Feb 19 2017 23:54:40 GMT-0800 (PST) 23:54:44 INFO - PROCESS | 12162 | No birth date. Using mtime. 23:54:44 INFO - PROCESS | 12162 | Using date: /tmp/tmp2UTfud/profile/extensions.ini = Sun Feb 19 2017 23:54:41 GMT-0800 (PST) 23:54:45 INFO - PROCESS | 12162 | No birth date. Using mtime. 23:54:45 INFO - PROCESS | 12162 | Using date: /tmp/tmp2UTfud/profile/blocklist.xml = Sun Feb 19 2017 23:54:40 GMT-0800 (PST) 23:54:45 INFO - PROCESS | 12162 | No birth date. Using mtime. 23:54:45 INFO - PROCESS | 12162 | Using date: /tmp/tmp2UTfud/profile/places.sqlite-shm = Sun Feb 19 2017 23:54:42 GMT-0800 (PST) 23:54:45 INFO - PROCESS | 12162 | No birth date. Using mtime. 23:54:45 INFO - PROCESS | 12162 | Using date: /tmp/tmp2UTfud/profile/places.sqlite-wal = Sun Feb 19 2017 23:54:45 GMT-0800 (PST) 23:54:45 INFO - PROCESS | 12162 | No birth date. Using mtime. 23:54:45 INFO - PROCESS | 12162 | Using date: /tmp/tmp2UTfud/profile/cert8.db = Sun Feb 19 2017 23:54:40 GMT-0800 (PST) 23:54:45 INFO - PROCESS | 12162 | No birth date. Using mtime. 23:54:45 INFO - PROCESS | 12162 | Using date: /tmp/tmp2UTfud/profile/startupCache = Sun Feb 19 2017 23:54:40 GMT-0800 (PST) 23:54:45 INFO - PROCESS | 12162 | No birth date. Using mtime. 23:54:45 INFO - PROCESS | 12162 | Using date: /tmp/tmp2UTfud/profile/addons.json = Sun Feb 19 2017 23:54:43 GMT-0800 (PST) 23:54:45 INFO - PROCESS | 12162 | No birth date. Using mtime. 23:54:45 INFO - PROCESS | 12162 | Using date: /tmp/tmp2UTfud/profile/cache2 = Sun Feb 19 2017 23:54:40 GMT-0800 (PST) 23:54:45 INFO - PROCESS | 12162 | __metrics Screen width/height:1600/1200 23:54:45 INFO - PROCESS | 12162 | colorDepth:24 23:54:45 INFO - PROCESS | 12162 | Browser inner width/height: 1024/697 23:54:45 INFO - PROCESS | 12162 | __metrics 23:54:45 INFO - PROCESS | 12162 | No birth date. Using mtime. 23:54:45 INFO - PROCESS | 12162 | Using date: /tmp/tmp2UTfud/profile/bookmarkbackups = Sun Feb 19 2017 23:54:43 GMT-0800 (PST) 23:54:45 INFO - PROCESS | 12162 | No birth date. Using mtime. 23:54:45 INFO - PROCESS | 12162 | Using date: /tmp/tmp2UTfud/profile/gmp = Sun Feb 19 2017 23:54:41 GMT-0800 (PST) 23:54:45 INFO - PROCESS | 12162 | No birth date. Using mtime. 23:54:45 INFO - PROCESS | 12162 | Using date: /tmp/tmp2UTfud/profile/extensions = Sun Feb 19 2017 23:54:41 GMT-0800 (PST) 23:54:45 INFO - PROCESS | 12162 | No birth date. Using mtime. 23:54:45 INFO - PROCESS | 12162 | Using date: /tmp/tmp2UTfud/profile/extensions.json = Sun Feb 19 2017 23:54:43 GMT-0800 (PST) 23:54:45 INFO - PROCESS | 12162 | No birth date. Using mtime. 23:54:45 INFO - PROCESS | 12162 | Using date: /tmp/tmp2UTfud/profile/secmod.db = Sun Feb 19 2017 23:54:40 GMT-0800 (PST) 23:54:45 INFO - PROCESS | 12162 | Stat failure 23:54:45 INFO - PROCESS | 12162 | No birth date. Using mtime. 23:54:45 INFO - PROCESS | 12162 | Using date: /tmp/tmp2UTfud/profile/.parentlock = Sun Feb 19 2017 23:54:40 GMT-0800 (PST) 23:54:45 INFO - PROCESS | 12162 | No birth date. Using mtime. 23:54:45 INFO - PROCESS | 12162 | Using date: /tmp/tmp2UTfud/profile/key3.db = Sun Feb 19 2017 23:54:40 GMT-0800 (PST) 23:54:45 INFO - PROCESS | 12162 | No birth date. Using mtime. 23:54:45 INFO - PROCESS | 12162 | Using date: /tmp/tmp2UTfud/profile/thumbnails = Sun Feb 19 2017 23:54:43 GMT-0800 (PST) 23:54:45 INFO - PROCESS | 12162 | No birth date. Using mtime. 23:54:45 INFO - PROCESS | 12162 | Using date: /tmp/tmp2UTfud/profile/compatibility.ini = Sun Feb 19 2017 23:54:41 GMT-0800 (PST) 23:54:45 INFO - PROCESS | 12162 | No birth date. Using mtime. 23:54:45 INFO - PROCESS | 12162 | Using date: /tmp/tmp2UTfud/profile/frequencyCap.json = Sun Feb 19 2017 23:54:43 GMT-0800 (PST) 23:54:45 INFO - PROCESS | 12162 | No birth date. Using mtime. 23:54:45 INFO - PROCESS | 12162 | Using date: /tmp/tmp2UTfud/profile/minidumps = Sun Feb 19 2017 23:54:40 GMT-0800 (PST) 23:54:45 INFO - PROCESS | 12162 | No birth date. Using mtime. 23:54:45 INFO - PROCESS | 12162 | Using date: /tmp/tmp2UTfud/profile/content-prefs.sqlite = Sun Feb 19 2017 23:54:42 GMT-0800 (PST) 23:54:45 INFO - PROCESS | 12162 | No birth date. Using mtime. 23:54:45 INFO - PROCESS | 12162 | Using date: /tmp/tmp2UTfud/profile/safebrowsing = Sun Feb 19 2017 23:54:45 GMT-0800 (PST) 23:54:45 INFO - PROCESS | 12162 | No birth date. Using mtime. 23:54:45 INFO - PROCESS | 12162 | Using date: /tmp/tmp2UTfud/profile/prefs.js = Sun Feb 19 2017 23:53:58 GMT-0800 (PST) 23:54:45 INFO - PROCESS | 12162 | No birth date. Using mtime. 23:54:45 INFO - PROCESS | 12162 | Using date: /tmp/tmp2UTfud/profile/sessionCheckpoints.json = Sun Feb 19 2017 23:54:44 GMT-0800 (PST) 23:54:45 INFO - PROCESS | 12162 | No birth date. Using mtime. 23:54:45 INFO - PROCESS | 12162 | Using date: /tmp/tmp2UTfud/profile/localstore.rdf = Sun Feb 19 2017 23:53:58 GMT-0800 (PST) 23:54:45 INFO - PROCESS | 12162 | No birth date. Using mtime. 23:54:45 INFO - PROCESS | 12162 | Using date: /tmp/tmp2UTfud/profile/places.sqlite = Sun Feb 19 2017 23:54:45 GMT-0800 (PST) 23:54:45 INFO - PROCESS | 12162 | No birth date. Using mtime. 23:54:45 INFO - PROCESS | 12162 | Using date: /tmp/tmp2UTfud/profile/permissions.sqlite = Sun Feb 19 2017 23:54:41 GMT-0800 (PST) 23:54:45 INFO - PROCESS | 12162 | No birth date. Using mtime. 23:54:45 INFO - PROCESS | 12162 | Using date: /tmp/tmp2UTfud/profile/directoryLinks.json = Sun Feb 19 2017 23:54:43 GMT-0800 (PST) 23:54:46 ERROR - PROCESS | 12162 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 23:54:47 INFO - TEST-INFO | 12162: exit 0 23:54:47 INFO - Browser initialized. 23:54:47 INFO - Running cycle 1/1 for dromaeo_css test... 23:54:47 INFO - TEST-INFO | started process 12287 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2UTfud/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:54:59 INFO - PROCESS | 12287 | RSS: Main: 153608192 23:54:59 INFO - PROCESS | 12287 | 23:58:04 INFO - PROCESS | 12287 | Cycle 1(1): loaded http://localhost:55505/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55505/tests/dromaeo/cssquery-ext.html) 23:58:05 INFO - PROCESS | 12287 | RSS: Main: 165208064 23:58:05 INFO - PROCESS | 12287 | 23:59:47 INFO - PROCESS | 12287 | ************************* 23:59:47 INFO - PROCESS | 12287 | A coding exception was thrown and uncaught in a Task. 23:59:47 INFO - PROCESS | 12287 | 23:59:47 ERROR - PROCESS | 12287 | Full message: TypeError: NetworkError when attempting to fetch resource. 23:59:47 INFO - PROCESS | 12287 | Full stack: 23:59:47 INFO - PROCESS | 12287 | ************************* 00:01:08 INFO - PROCESS | 12287 | Cycle 1(1): loaded http://localhost:55505/tests/dromaeo/cssquery-ext.html (next: http://localhost:55505/tests/dromaeo/cssquery-jquery.html) 00:01:08 INFO - PROCESS | 12287 | RSS: Main: 157184000 00:01:08 INFO - PROCESS | 12287 | 00:04:10 INFO - PROCESS | 12287 | Cycle 1(1): loaded http://localhost:55505/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55505/tests/dromaeo/cssquery-mootools.html) 00:04:11 INFO - PROCESS | 12287 | RSS: Main: 160837632 00:04:11 INFO - PROCESS | 12287 | 00:07:13 INFO - PROCESS | 12287 | Cycle 1(1): loaded http://localhost:55505/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55505/tests/dromaeo/cssquery-prototype.html) 00:07:14 INFO - PROCESS | 12287 | RSS: Main: 158515200 00:07:14 INFO - PROCESS | 12287 | 00:10:17 INFO - PROCESS | 12287 | Cycle 1(1): loaded http://localhost:55505/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55505/tests/dromaeo/cssquery-yui.html) 00:10:17 INFO - PROCESS | 12287 | RSS: Main: 161914880 00:10:17 INFO - PROCESS | 12287 | 00:13:20 INFO - PROCESS | 12287 | Cycle 1(1): loaded http://localhost:55505/tests/dromaeo/cssquery-yui.html (next: http://localhost:55505/tests/dromaeo/cssquery-dojo.html) 00:13:20 INFO - PROCESS | 12287 | RSS: Main: 161464320 00:13:20 INFO - PROCESS | 12287 | 00:13:20 INFO - PROCESS | 12287 | __start_tp_report 00:13:20 INFO - PROCESS | 12287 | _x_x_mozilla_page_load 00:13:20 INFO - PROCESS | 12287 | _x_x_mozilla_page_load_details 00:13:20 INFO - PROCESS | 12287 | |i|pagename|runs| 00:13:20 INFO - PROCESS | 12287 | |0;dojo.html;2572.794176465883;2572.8199026068182;2586.780123689486;2606.973930260698;2608;4709.976450117749;4757.1437141314545;4758.6906851054655;4766.475687674358;4873.366462359888;4854.199057155565;4890.73100979446;4947.158982972895;4950.405951285851;4992.276119962603;4216.662666986634;4502.122086193193;4586.587207151356;4607.677462577621;4609.124266389392;4598.563136502027;4606.286025666027;4606.723596584216;4609.44686637604;4611.216093264153;4403.691741578091;4434.645228381754;4452.666050046259;4503.211937910853;4519.050999290153;4466.709663871838;4466.7766611669285;4474.6420286377015;4530.116627257686;4535.569120933539;4416.116776644684;4447.7776111194635;4451.955480445186;4473.843415480443;4496.820127194908;4768.451628062782;4808.591269742076;4872.952315252202;4891.070696567641;4894.437139728905;5150.587952963754;5187.792488300463;5189.325387699612;5204.4535323791215;5227.477252274742;5208.427073021964;5221.791128354861;5228.79084836606;5236.293100431431;5272.551833094191;5008.348914641073;5008.874778130576;5012.649114561982;5020.497950205024;5033.345665063518;5226.738663066832;5281.445448227958;5332.573394128461;5333.466653334713;5343.492368225013;5136.075506408883;5137.614678899097;5154.896902061938;5167.948320516822;5201.895962080737;5202.895942081137;5241.475852414728;5258.2112683097075;5263.184206448007;5309.734513274321;5063.518965698253;5128.076946149654;5176.456472070378;5188.22176673502;5213.530782229617;5231.843044708665;5242.868928276747;5243.370795504564;5255.947440525622;5257.8159764408065;4512.300593408027;4521.073179998159;4524.208263553931;4530.252508336088;4568.748718820497;3856.787876666754;3891.0272431892026;3907.980460097738;3930.4104384342577;3952.071263253133;4495.797689103998;4523.796429160696;4544.636429085665;4553.134904368159;4603.6777425579885;5234.18870075139;5240.6069544784295;5244.108501554745;5269.130593452114;5272.024675435056;4592.058627981256;4593.28804035375;4594.609458196024;4597.448306203277;4610.515895830889;4644.60520855731;4684.189486308256;4696.154692155376;4696.225122854691;4737.478877323491;4860.222364421676;4882.658213924991;4882.998985208095;4896.290037944549;4903.215485522299;5242.816501422432;5244.213367994831;5257.842264732064;5259.947400525945;5274.815381461631;4935;4943.802247910044;4945.357103576512;4951.554360107608;4971.1797553403285;5163.225516172604;5210.8957820844125;5220.321358223407;5236.136037553761;5246.632735708464;5053;5097.6941383517105;5142.897142057063;5198.454162312903;5289.656172348785;5045.445001049955;5050.77271522775;5058.822941197041;5065.518775716301;5084.720340381315;5046.19260918266;5056.84829455117;5058.620603454682;5067.138586440387;5102.69383836971;4901.583365413837;4922.581580565692;4958.181899986462;4974.527419895104;5034.345535080416;5161.277421161116;5220.060389129993;5225.216217567396;5260.658057226421;5276.683398996072;5060.240963855451;5087.262346959744;5092.694438333712;5114.539691427789;5126 00:13:20 INFO - PROCESS | 12287 | |1;ext.html;509.9209622508511;524.716653007376;544.3060098374572;587.6767777722251;587.8412828536293;33658.83170584147;37123.443148352795;37238.441423378674;37263;37265.441018384714;36846.26307473849;37568.81215593925;37640.43539346912;37653.81173094137;37754.8112259439;7421.554706717588;7667.731629392944;9069.410488318272;9493.382930109556;9503.904960950405;9352.766180845465;9427.622895084189;9430.198433133157;9446.055394460533;9451.763705907339;9333.253339732804;9421.48181849998;9436.669716559922;9438.858417123714;9469.621215151386;9027.232685221765;9069;9072.274218062572;9086.227670648002;9109.498977556232;8924.687635932776;8962.910370896338;8984.955075224647;8988.820223595558;9045.68340108093;32559.51160732591;32807.50788738171;32874.6712532874;32944.17639559006;33073.83463082693;3142.905712828596;3159.1312389092955;3205.983970080158;3247.171971147366;3280.3439312137666;24286.757132428625;24649.753502465104;25004.624930626054;25030.749692503025;25329.078183408797;3212.293295475015;3229.612446506411;3238.951415728766;3259.5273685315733;3275.901722948315;217709;217821;218384;219049;219387;38244.23511529754;39411.60588394137;44581.33128003083;45850.77074614605;46131.76934115375;48714;48884;49079;49242.507574923795;49298;11186.888131118585;11246.606368777053;11299.830502542469;11329.206955513035;11361.375124368074;9222.76943076415;9288;9338.859917101248;9422.90577094234;9431.245500359955;15345.925785195153;15388.923055384652;15426.537203883901;15463.768043479356;15574;6474.971035915431;6506.739730410825;6579.30917253691;6657.367550082734;6801.829954251183;741.3142842870324;748.3863232149694;756.2324240895496;764.6176911544228;765.6822418696179;24550.263492095266;25871.353216169748;25901.352466188495;26046.869765651052;26463.735362646126;919.5126582911067;935.3592788939462;942.9198518125899;951.3293128344501;962.0667952086465;12936.223826570436;13934.225579385404;13994.790078148631;14156;14195.503157389441;293102;294951;295534;296399;297540;47418.52581474141;47470.76264618655;47747.52252477431;47778;47918;1158.2992289664633;1169.8830116988404;1207.5954555224098;1211.5820042085466;1215.4166000319897;23687.289381318587;24212.878935605208;25353.619695704947;25821.225363239133;27520.58719119255;39936.4009539863;39950.2009959805;40260;40383.3942490857;40388.394174087996;280613;283552;285024;285629;285700;22449;22737;23311.41721456977;23374.532509349378;24063.157789477285;35289.47065793964;35405.46891796677;35503;35786;36265.456018159224;30504.237394065327;30581.388372231984;30790.538141928337;31433.528497072104;31800.25098619598;29395.559066614445;29768.553471698375;29781.553276700433;30246.395072098876;30258;30754.077377678706;30991.380172395973;31078.533821992234;31121.8443907779;31140.53289200709;25964.792992595085;27044.45911081807;27815.582766258118;27834;27899.16302510928;21554.461138471663;21760.238391656218;21820.781792181875;21879.453013674785;22168.445788855406 00:13:20 INFO - PROCESS | 12287 | |2;jquery.html;379.8708439130695;390.9257241124186;393.2351576184321;404.7065877239004;415.93760935859615;15637.765433518493;17494.825051749478;17564.648707025866;17669.46991590251;17677.646447071067;16738.66522669546;17846.553836154104;17932.462026139205;18147.092645367713;18384.908075459636;5129.2562578426105;5342;5345.1714984177515;5345.33183352081;5353.866153346178;5220.6606570572985;5464.371597266326;5493.478119578654;5546.500814926656;5596.076647353182;5465.59008074394;5553.666779993193;5570.275864137657;5570.498655121059;5575.107982722765;6223.4398904098625;6235.875282494348;6260.092286618415;6264.9686751566405;6272.435480806704;5318.494742999409;5447.509724124848;5492.093804522249;5557.5831812614215;5580.581456390787;21118.577628447347;21236.30109591229;21395.572088558143;21604.243851465122;21773.129074836987;2015.0932080563805;2136.0921608316403;2368.6447032945025;2504.5742223821994;2510.2469259222344;9109.180173784325;9560.904390956071;9620.71137865865;9900.752481188028;9966.252531060127;1623.7645541396432;1657.991710041454;1662.8752843536784;1697.1599058466015;1707.6670049340385;69048.30951690419;69335;70862.64568677175;71508.64245678738;74686.62656686736;10743.946280268705;10814.945925270324;10837.133029357632;10999.560017599206;11052.447377631086;15073.095614263179;15100.697986040217;15107.54677359681;15111.093334399971;15237.619059523378;6597.60414375139;6641.203055633355;6826.385625293648;6836.38472537474;6878.27778083294;5964.1650168976375;5964.612300200473;6083;6102.572819902653;6107.419795119546;4493.910331695777;4666.32338310943;4691.366665500113;4741.454732705714;4854;1950.7854136045023;1993.641144593958;2014.6071516054521;2016.2136766661015;2021.9292324768562;773.1881524399358;790.632355954484;804.8591496488208;822.7655118291378;829.1749709039544;16851.241694123793;17014.659706806044;17364;17475;17959.281628734967;790.3361176611673;798.4171554765054;804.1074407407773;810.8337790752881;810.8864758933754;9269.304802139866;9703.66037188695;9731.124198821999;9745.317827752131;9757.560909759079;326327;328886;330956;482531;486762;69637;83629;85250.5737471321;91258;92486.83809228601;932.6922115701938;967.1537404770826;984.2175470500969;984.3945973226414;985.531872360623;15421;15659.843401565839;16366.75449868229;16420.589485262488;16689;36989.630103699696;38186;38288;38342.616573833904;38557.807210963765;47736.5226347746;50655.98688026293;52963;53318;53449.73275133599;7895.210478952059;7986.480878742863;8007.079185893464;8039.598020098854;8050.718224862102;10940.39827809478;11506.769864602493;11549;11761.411929403666;12140.575079872162;10139.290249682746;10304.845427318447;10470.424126673106;10584.682459526226;10622.8406573903;10422.270441069355;10633.989770971746;10798.460076996276;10838.295510791773;11061.889381106086;9746.074122958307;10338.379697218192;10500.317479363817;10689.786204275715;11174.608888689181;7529.962350188214;7599.088109426904;7647.464677472523;7932.881006785018;7991.120976692443;9197.862032069657;9208.677696280598;9329.253659707343;9346.953265233631;9610 00:13:20 INFO - PROCESS | 12287 | |3;mootools.html;1352.9391177397017;1357.4570171931227;1360.387825478535;1366.52854765106;1372.9794053089201;7221.458390620704;7476.140243871956;7671.7698469045945;7692.0384951881015;7705.306522412981;7648.158702542716;7748.922510774891;7758.301752842243;7763.06843178818;7780.416468764837;2039.6838490033995;2054.1577953039255;2066.803653652908;2069.5550456651818;2071.0576687607104;1972.5265936175358;2024.9797502025012;2044.1194101426722;2049.9282525111626;2051.4358551398336;2010.4270282969358;2030.5735795482967;2033.7356143701372;2045.9590808183827;2048.2114385961436;2058.557410156816;2071.886046267462;2072.823809976154;2081.2819577245828;2088.2691058129535;1763.902985335806;1817.9909100454413;2020.9696854547194;2029.6346657601625;2049.477383267257;7273.6363181841225;7501.6999320027135;7513.474056816025;7682.846343073164;7699.576523291216;4389.561043895585;4511.729296242236;4522.7286362818;4530.6602004849765;4538.02432477019;2697.1234348836706;2762.613234147221;2824.9011284605144;2826.547752359613;2827.618271533337;1061.4427425601543;1074.2319241742114;1086.5165001574303;1100.4703462187565;1160.9187356885022;9962.302638815287;9981.30130890838;10042.69871903844;10068.194544436428;10094.49527523621;3628.32875917956;3717.2565486902727;3738.36447803874;3790.886273411802;3860.4402361657403;8174.67301307941;8181.631826567819;8193;8193.385496087818;8206.93309869713;4267.658587313008;4298.914021719548;4319.654427645781;4324.329728892027;4353.303471444554;3855.190410013872;4004.8598299059977;4014.4781178446615;4037.0714735610386;4048.5748996355546;3221.259110404573;3261.445554255782;3320.601527816629;3322.103032181346;3358.177246574556;953.1374106433689;955.6511873166384;963.6193703487223;968.5157421289356;974.8927617962019;736.1423941108549;770.1335997003371;783.5063909736944;784.5136015670321;787.1066339704477;4799.856004319876;4874.634402419832;4892.486288939681;4904.754762261872;5036.294918711384;844.5059640110481;873.8689196620558;874.9125087491327;878.5343767803074;893.6246776353947;3164.4620414529586;3232.7413806895393;3350.480675495302;3355.563776709012;3458.9827050864583;7976.242256985577;8313.08556058833;8586.09845966177;8777.42946708462;9264.44413335202;8780.736577902673;9084.772880677903;9124.087591240823;9127.954360228156;9147.0852914708;6888.207856096513;7008;7227.747028853965;7252.818679532948;7308.232635573295;4945.455999839943;5037.722925239147;5075.086484432837;5107.744612769272;5157.535821776059;7808.141104478616;8065.112837587978;8155.510669359857;8209.096999330188;8210.753677389688;8160.30637395828;8289.502629842229;8382;8388.161183881808;8573.442726222776;6012.969935150296;6183.84540386494;6193.876122477615;6207.689615519115;6217.222847144107;7099.07711997437;7123.608201548965;7147.213806481179;7197;7213;6507.544471887112;6522.673866306571;6524.967375163284;6526.869462610816;6561.114249576246;6318.62088274705;6412.422881940648;6438.645874476949;6599.208095028627;6610.10763546915;5516.062269414289;5538.252335934597;5571;5575.776968921197;5583.330000399978;4275.273203555465;4330.090680957034;4412.404325416028;4428.468583769839;4497.3928519650735;4117.21772863155;4129.793510324532;4272.124214536075;4311.8490852820005;4348.565143485753 00:13:20 INFO - PROCESS | 12287 | |4;prototype.html;325.2730148118954;339.9133221028638;345.51282691405123;346.3436787288089;348.27906234095417;5097.515736005078;5173.663711858736;5178.326817513718;5182.611304152189;5183.352080989872;5049.596032317415;5055.393352797661;5062.924056139161;5118.744062796854;5138.871528211796;3788.2802267569214;3896.3960586109197;3914.9804250978705;3931.429942658327;4096.631303182713;3922.745021573603;3929.9803500982457;3934.131287638363;3938.5230538548044;3942.231264903345;3884.76691398517;3900.5904380040115;3901.161250331178;3902.7072969527258;3915.588863169369;3708.7774733516076;3907.6678482328894;3908.9804550977346;3910.667593254577;3910.8240129194255;3417.3165366926464;3443.500692399613;3447.5862896452586;3447.931041379158;3450.861965521376;4920.089783390084;4946.653734238605;4952.182889823175;4952.207646776535;4982.352294201767;4480.798364073625;4489.079738653568;4492.550744925481;4494.5730155635165;4506.408814931395;5204.9479505204845;5259.316288882459;5271.393789714193;5289.814856480005;5358.91961620576;3697.0018095114438;3705.7220708446703;3710.495415594463;3714.182879766447;3718.5909549949483;5254.658447200958;5527.944720552823;5548.834744703617;5569.220309156722;5586.385497595261;3579.7673151245085;3681.392570225936;3785.507883975121;3817.904552386157;3827.234553089393;3816.637419445148;3854.8650797221962;3858.5369755629504;3866.516685414323;3866.883993480144;4438.201123797683;4445.444319460067;4447;4450.1989641864775;4458.328126952393;5103.515166059218;5314.70769107695;5322.201669749568;5339.318114793905;5341.439148889388;1248.2759999200441;1254.7804134276648;1254.9646541603213;1255.918365306252;1257.6289994451658;811.9918800811917;833.4041160570222;847.8092429203504;856.1609622569792;858.5621333120029;4539.501964351756;4542.84100056503;4549.818007279738;4550.5449455054895;4555.3850230218495;5238.109521381374;5302.92045619316;5316.893662126736;5319.202119682079;5345.572354211654;4291.313389857608;4511.639068874482;4523.479799823062;4538.523455037173;4539.296409056601;2811.620431241797;2824.717528247159;2828.476731804623;2835.6738975017724;2840.218939791574;4975.701457912537;4986.626003049786;4999.375078115236;5005.721252579566;5009;5107.361579802525;5389.892202156013;5444.005796811738;5453.781848726086;5489.204065410573;4960.8759781005765;4975.825846095369;4979.90040199194;5001.699898006132;5008.373953255843;5261.185640410672;5290.682559046316;5300.522952934254;5305.416404195613;5308.708021058879;5073.441921388718;5180.9740951295;5187.922181167361;5422.62041657096;5456.809011684731;5015.448300686849;5168.431472537943;5177.980587570925;5180.145276029413;5181.5077567631015;3758.6993040557236;3783.754055986352;3801.8099095044586;3811.5997820228476;3836.7506112102624;5482.725863706719;5489.4236105209975;5490.560755139659;5499.862503437446;5502.477264659851;5071.366079240095;5319.813806516908;5333.359996800409;5350.5184533392185;5361.651492652965;5287.471252874681;5300.7879684813715;5304.867378315573;5313.7682173488;5328.49379308965;5272.815451459181;5279.313689220377;5290.153575427913;5300.496452836975;5329.120695085423;5210.27056212123;5328.973355133199;5384.896096300327;5386.649867758583;5417.404085550507;5243.737813109253;5257.868553286045;5259.395169555397;5260.710660913686;5270.710110943782 00:13:20 INFO - PROCESS | 12287 | |5;yui.html;627.7175271127993;633.8003528888403;706.5230969095859;722.6242353975932;741.1921006103346;11927.10546708997;12491.500339986413;12641.367931603429;12753.681157971032;12766.170198937063;12946.352682365867;12952.417141228643;13043.478260869553;13068.346582670876;13097.214167149978;812.086402796855;826.5412695953743;830.0537387378394;830.4975489828657;831.4927893984665;806.5080301016404;828.7306625346756;848.6308455821707;849.5157760076759;850.4897061762923;861.4615865084322;865.9307255419551;866.0127454701645;866.2290561400358;866.6230189867427;872.5070335260557;872.5811610426999;872.8297981893534;872.9301655867547;873.6330741088694;633.8700566383881;634.4702173684979;636.6275728698716;637.5409705012383;641.2113100885937;12429.627111186679;12792.808107878389;12800.743985120338;12808.679783005406;14100.435982560686;12449.439775210138;12889.226646401246;12924.612261632072;12978.935105324508;13252.602421927357;10341.534630941625;10423.583056677724;10551.73620659482;10555.091882274779;10696.625618103408;3150.3384289299274;3173.1908363367424;3265.102096923365;3308.801471911669;3312.7018568328967;59242.70378648122;60646;60931.69534152345;61358.69320653413;61645;9538.761530961643;9596.95201523988;9637.662681806103;9837.655682051094;9866.260030497742;18316.63366732658;18319.725204121947;18371.448856534324;18394.356197533023;18445.5388615283;5990.9101363479585;6006.759729610767;6256;6267.091271765659;6293.496520278367;3391.9491207631904;3476.203023779551;3513.508108864761;3530.1880567469625;3547.5388199534414;2810.3255218747354;2867.240181351943;2882.610847535556;2887.797854150231;2906.3170155013554;453.75950746104616;459.2582978489782;469.51170782385935;471.69104236724314;472.2302646685949;578.8234588450563;613.6072913335469;614.3365165621118;622.1973653986307;632.601461079517;8065.6908816985115;8384.958075209584;8397.580120993925;8401.453905496246;8610.612522436506;450.56970593083526;482.312704396234;483.47784392856335;489.6155721635586;522.1671434062664;3697.2859604005107;4019.517657881073;4132.2561938850695;4150.418941348214;4167.437395951569;54646.72676636671;57418;58892.70553647204;58897.411025889196;59494.70252648709;51840;52355;52778;52823;53257.46742532525;44743.32885006727;45175.09649807051;45312.32031519596;45859;46612.3008154878;7686.730964416219;7964.9203507966495;8037.397195210266;8053.516788992679;8103.635336409993;31523.21191970219;31805.840970794998;32180;32730;33619.49570756392;38128.428073579475;39881.2023759529;40273.798631006655;40432.59567404288;41907.7904610475;9082.772930676785;9282.396644218104;9313.906860931304;9448.763780905532;9469.66856160031;11032;11122.221444498808;11283.435828208392;11286.266392684449;11468.770624587629;9706.126448619376;9765.804683906425;9859.556319965475;9876.259280553844;9959.05388988045;9172.495512746864;9182.449053056838;9190.448573085636;9286.860697089414;9368.156865882103;9522.291407222914;9531.618735250513;9583.472908990072;9890.653827116017;10100.898991009995;7488.7004519820775;7687.730929417444;7874.803129921798;7888.921110788819;7920.801979950547;7883.821564600057;8006.834737252167;8202.302804261704;8536.44513106646;8563.186497282748 00:13:20 INFO - PROCESS | 12287 | __end_tp_report 00:13:20 INFO - PROCESS | 12287 | __start_cc_report 00:13:20 INFO - PROCESS | 12287 | _x_x_mozilla_cycle_collect,368 00:13:20 INFO - PROCESS | 12287 | __end_cc_report 00:13:20 INFO - PROCESS | 12287 | __startTimestamp1487578400905__endTimestamp 00:13:20 INFO - PROCESS | 12287 | 00:13:20 INFO - PROCESS | 12287 | ------- Summary: start ------- 00:13:20 INFO - PROCESS | 12287 | Number of tests: 6 00:13:20 INFO - PROCESS | 12287 | 00:13:20 INFO - PROCESS | 12287 | [#0] dojo.html Cycles:165 Average:4836.98 Median:4974.53 stddev:518.99 (10.4%) stddev-sans-first:489.25 00:13:20 INFO - PROCESS | 12287 | Values: 2572.8 2572.8 2586.8 2607.0 2608.0 4710.0 4757.1 4758.7 4766.5 4873.4 4854.2 4890.7 4947.2 4950.4 4992.3 4216.7 4502.1 4586.6 4607.7 4609.1 4598.6 4606.3 4606.7 4609.4 4611.2 4403.7 4434.6 4452.7 4503.2 4519.1 4466.7 4466.8 4474.6 4530.1 4535.6 4416.1 4447.8 4452.0 4473.8 4496.8 4768.5 4808.6 4873.0 4891.1 4894.4 5150.6 5187.8 5189.3 5204.5 5227.5 5208.4 5221.8 5228.8 5236.3 5272.6 5008.3 5008.9 5012.6 5020.5 5033.3 5226.7 5281.4 5332.6 5333.5 5343.5 5136.1 5137.6 5154.9 5167.9 5201.9 5202.9 5241.5 5258.2 5263.2 5309.7 5063.5 5128.1 5176.5 5188.2 5213.5 5231.8 5242.9 5243.4 5255.9 5257.8 4512.3 4521.1 4524.2 4530.3 4568.7 3856.8 3891.0 3908.0 3930.4 3952.1 4495.8 4523.8 4544.6 4553.1 4603.7 5234.2 5240.6 5244.1 5269.1 5272.0 4592.1 4593.3 4594.6 4597.4 4610.5 4644.6 4684.2 4696.2 4696.2 4737.5 4860.2 4882.7 4883.0 4896.3 4903.2 5242.8 5244.2 5257.8 5259.9 5274.8 4935.0 4943.8 4945.4 4951.6 4971.2 5163.2 5210.9 5220.3 5236.1 5246.6 5053.0 5097.7 5142.9 5198.5 5289.7 5045.4 5050.8 5058.8 5065.5 5084.7 5046.2 5056.8 5058.6 5067.1 5102.7 4901.6 4922.6 4958.2 4974.5 5034.3 5161.3 5220.1 5225.2 5260.7 5276.7 5060.2 5087.3 5092.7 5114.5 5126.0 00:13:20 INFO - PROCESS | 12287 | 00:13:20 INFO - PROCESS | 12287 | [#1] ext.html Cycles:180 Average:40901.74 Median:18043.93 stddev:70197.37 (389.0%) stddev-sans-first:70328.78 00:13:20 INFO - PROCESS | 12287 | Values: 509.9 524.7 544.3 587.7 587.8 33658.8 37123.4 37238.4 37263.0 37265.4 36846.3 37568.8 37640.4 37653.8 37754.8 7421.6 7667.7 9069.4 9493.4 9503.9 9352.8 9427.6 9430.2 9446.1 9451.8 9333.3 9421.5 9436.7 9438.9 9469.6 9027.2 9069.0 9072.3 9086.2 9109.5 8924.7 8962.9 8985.0 8988.8 9045.7 32559.5 32807.5 32874.7 32944.2 33073.8 3142.9 3159.1 3206.0 3247.2 3280.3 24286.8 24649.8 25004.6 25030.7 25329.1 3212.3 3229.6 3239.0 3259.5 3275.9 217709.0 217821.0 218384.0 219049.0 219387.0 38244.2 39411.6 44581.3 45850.8 46131.8 48714.0 48884.0 49079.0 49242.5 49298.0 11186.9 11246.6 11299.8 11329.2 11361.4 9222.8 9288.0 9338.9 9422.9 9431.2 15345.9 15388.9 15426.5 15463.8 15574.0 6475.0 6506.7 6579.3 6657.4 6801.8 741.3 748.4 756.2 764.6 765.7 24550.3 25871.4 25901.4 26046.9 26463.7 919.5 935.4 942.9 951.3 962.1 12936.2 13934.2 13994.8 14156.0 14195.5 293102.0 294951.0 295534.0 296399.0 297540.0 47418.5 47470.8 47747.5 47778.0 47918.0 1158.3 1169.9 1207.6 1211.6 1215.4 23687.3 24212.9 25353.6 25821.2 27520.6 39936.4 39950.2 40260.0 40383.4 40388.4 280613.0 283552.0 285024.0 285629.0 285700.0 22449.0 22737.0 23311.4 23374.5 24063.2 35289.5 35405.5 35503.0 35786.0 36265.5 30504.2 30581.4 30790.5 31433.5 31800.3 29395.6 29768.6 29781.6 30246.4 30258.0 30754.1 30991.4 31078.5 31121.8 31140.5 25964.8 27044.5 27815.6 27834.0 27899.2 21554.5 21760.2 21820.8 21879.5 22168.4 00:13:20 INFO - PROCESS | 12287 | 00:13:20 INFO - PROCESS | 12287 | [#2] jquery.html Cycles:180 Average:24842.02 Median:27892.62 stddev:66027.56 (236.7%) stddev-sans-first:66187.24 00:13:20 INFO - PROCESS | 12287 | Values: 379.9 390.9 393.2 404.7 415.9 15637.8 17494.8 17564.6 17669.5 17677.6 16738.7 17846.6 17932.5 18147.1 18384.9 5129.3 5342.0 5345.2 5345.3 5353.9 5220.7 5464.4 5493.5 5546.5 5596.1 5465.6 5553.7 5570.3 5570.5 5575.1 6223.4 6235.9 6260.1 6265.0 6272.4 5318.5 5447.5 5492.1 5557.6 5580.6 21118.6 21236.3 21395.6 21604.2 21773.1 2015.1 2136.1 2368.6 2504.6 2510.2 9109.2 9560.9 9620.7 9900.8 9966.3 1623.8 1658.0 1662.9 1697.2 1707.7 69048.3 69335.0 70862.6 71508.6 74686.6 10743.9 10814.9 10837.1 10999.6 11052.4 15073.1 15100.7 15107.5 15111.1 15237.6 6597.6 6641.2 6826.4 6836.4 6878.3 5964.2 5964.6 6083.0 6102.6 6107.4 4493.9 4666.3 4691.4 4741.5 4854.0 1950.8 1993.6 2014.6 2016.2 2021.9 773.2 790.6 804.9 822.8 829.2 16851.2 17014.7 17364.0 17475.0 17959.3 790.3 798.4 804.1 810.8 810.9 9269.3 9703.7 9731.1 9745.3 9757.6 326327.0 328886.0 330956.0 482531.0 486762.0 69637.0 83629.0 85250.6 91258.0 92486.8 932.7 967.2 984.2 984.4 985.5 15421.0 15659.8 16366.8 16420.6 16689.0 36989.6 38186.0 38288.0 38342.6 38557.8 47736.5 50656.0 52963.0 53318.0 53449.7 7895.2 7986.5 8007.1 8039.6 8050.7 10940.4 11506.8 11549.0 11761.4 12140.6 10139.3 10304.8 10470.4 10584.7 10622.8 10422.3 10634.0 10798.5 10838.3 11061.9 9746.1 10338.4 10500.3 10689.8 11174.6 7530.0 7599.1 7647.5 7932.9 7991.1 9197.9 9208.7 9329.3 9347.0 9610.0 00:13:20 INFO - PROCESS | 12287 | 00:13:20 INFO - PROCESS | 12287 | [#3] mootools.html Cycles:180 Average:4830.24 Median:5091.42 stddev:2692.92 (52.9%) stddev-sans-first:2687.79 00:13:20 INFO - PROCESS | 12287 | Values: 1352.9 1357.5 1360.4 1366.5 1373.0 7221.5 7476.1 7671.8 7692.0 7705.3 7648.2 7748.9 7758.3 7763.1 7780.4 2039.7 2054.2 2066.8 2069.6 2071.1 1972.5 2025.0 2044.1 2049.9 2051.4 2010.4 2030.6 2033.7 2046.0 2048.2 2058.6 2071.9 2072.8 2081.3 2088.3 1763.9 1818.0 2021.0 2029.6 2049.5 7273.6 7501.7 7513.5 7682.8 7699.6 4389.6 4511.7 4522.7 4530.7 4538.0 2697.1 2762.6 2824.9 2826.5 2827.6 1061.4 1074.2 1086.5 1100.5 1160.9 9962.3 9981.3 10042.7 10068.2 10094.5 3628.3 3717.3 3738.4 3790.9 3860.4 8174.7 8181.6 8193.0 8193.4 8206.9 4267.7 4298.9 4319.7 4324.3 4353.3 3855.2 4004.9 4014.5 4037.1 4048.6 3221.3 3261.4 3320.6 3322.1 3358.2 953.1 955.7 963.6 968.5 974.9 736.1 770.1 783.5 784.5 787.1 4799.9 4874.6 4892.5 4904.8 5036.3 844.5 873.9 874.9 878.5 893.6 3164.5 3232.7 3350.5 3355.6 3459.0 7976.2 8313.1 8586.1 8777.4 9264.4 8780.7 9084.8 9124.1 9128.0 9147.1 6888.2 7008.0 7227.7 7252.8 7308.2 4945.5 5037.7 5075.1 5107.7 5157.5 7808.1 8065.1 8155.5 8209.1 8210.8 8160.3 8289.5 8382.0 8388.2 8573.4 6013.0 6183.8 6193.9 6207.7 6217.2 7099.1 7123.6 7147.2 7197.0 7213.0 6507.5 6522.7 6525.0 6526.9 6561.1 6318.6 6412.4 6438.6 6599.2 6610.1 5516.1 5538.3 5571.0 5575.8 5583.3 4275.3 4330.1 4412.4 4428.5 4497.4 4117.2 4129.8 4272.1 4311.8 4348.6 00:13:20 INFO - PROCESS | 12287 | 00:13:20 INFO - PROCESS | 12287 | [#4] prototype.html Cycles:180 Average:4361.43 Median:5000.54 stddev:1283.80 (25.7%) stddev-sans-first:1251.14 00:13:20 INFO - PROCESS | 12287 | Values: 325.3 339.9 345.5 346.3 348.3 5097.5 5173.7 5178.3 5182.6 5183.4 5049.6 5055.4 5062.9 5118.7 5138.9 3788.3 3896.4 3915.0 3931.4 4096.6 3922.7 3930.0 3934.1 3938.5 3942.2 3884.8 3900.6 3901.2 3902.7 3915.6 3708.8 3907.7 3909.0 3910.7 3910.8 3417.3 3443.5 3447.6 3447.9 3450.9 4920.1 4946.7 4952.2 4952.2 4982.4 4480.8 4489.1 4492.6 4494.6 4506.4 5204.9 5259.3 5271.4 5289.8 5358.9 3697.0 3705.7 3710.5 3714.2 3718.6 5254.7 5527.9 5548.8 5569.2 5586.4 3579.8 3681.4 3785.5 3817.9 3827.2 3816.6 3854.9 3858.5 3866.5 3866.9 4438.2 4445.4 4447.0 4450.2 4458.3 5103.5 5314.7 5322.2 5339.3 5341.4 1248.3 1254.8 1255.0 1255.9 1257.6 812.0 833.4 847.8 856.2 858.6 4539.5 4542.8 4549.8 4550.5 4555.4 5238.1 5302.9 5316.9 5319.2 5345.6 4291.3 4511.6 4523.5 4538.5 4539.3 2811.6 2824.7 2828.5 2835.7 2840.2 4975.7 4986.6 4999.4 5005.7 5009.0 5107.4 5389.9 5444.0 5453.8 5489.2 4960.9 4975.8 4979.9 5001.7 5008.4 5261.2 5290.7 5300.5 5305.4 5308.7 5073.4 5181.0 5187.9 5422.6 5456.8 5015.4 5168.4 5178.0 5180.1 5181.5 3758.7 3783.8 3801.8 3811.6 3836.8 5482.7 5489.4 5490.6 5499.9 5502.5 5071.4 5319.8 5333.4 5350.5 5361.7 5287.5 5300.8 5304.9 5313.8 5328.5 5272.8 5279.3 5290.2 5300.5 5329.1 5210.3 5329.0 5384.9 5386.6 5417.4 5243.7 5257.9 5259.4 5260.7 5270.7 00:13:20 INFO - PROCESS | 12287 | 00:13:20 INFO - PROCESS | 12287 | [#5] yui.html Cycles:180 Average:13530.05 Median:59070.06 stddev:16715.49 (28.3%) stddev-sans-first:16734.30 00:13:20 INFO - PROCESS | 12287 | Values: 627.7 633.8 706.5 722.6 741.2 11927.1 12491.5 12641.4 12753.7 12766.2 12946.4 12952.4 13043.5 13068.3 13097.2 812.1 826.5 830.1 830.5 831.5 806.5 828.7 848.6 849.5 850.5 861.5 865.9 866.0 866.2 866.6 872.5 872.6 872.8 872.9 873.6 633.9 634.5 636.6 637.5 641.2 12429.6 12792.8 12800.7 12808.7 14100.4 12449.4 12889.2 12924.6 12978.9 13252.6 10341.5 10423.6 10551.7 10555.1 10696.6 3150.3 3173.2 3265.1 3308.8 3312.7 59242.7 60646.0 60931.7 61358.7 61645.0 9538.8 9597.0 9637.7 9837.7 9866.3 18316.6 18319.7 18371.4 18394.4 18445.5 5990.9 6006.8 6256.0 6267.1 6293.5 3391.9 3476.2 3513.5 3530.2 3547.5 2810.3 2867.2 2882.6 2887.8 2906.3 453.8 459.3 469.5 471.7 472.2 578.8 613.6 614.3 622.2 632.6 8065.7 8385.0 8397.6 8401.5 8610.6 450.6 482.3 483.5 489.6 522.2 3697.3 4019.5 4132.3 4150.4 4167.4 54646.7 57418.0 58892.7 58897.4 59494.7 51840.0 52355.0 52778.0 52823.0 53257.5 44743.3 45175.1 45312.3 45859.0 46612.3 7686.7 7964.9 8037.4 8053.5 8103.6 31523.2 31805.8 32180.0 32730.0 33619.5 38128.4 39881.2 40273.8 40432.6 41907.8 9082.8 9282.4 9313.9 9448.8 9469.7 11032.0 11122.2 11283.4 11286.3 11468.8 9706.1 9765.8 9859.6 9876.3 9959.1 9172.5 9182.4 9190.4 9286.9 9368.2 9522.3 9531.6 9583.5 9890.7 10100.9 7488.7 7687.7 7874.8 7888.9 7920.8 7883.8 8006.8 8202.3 8536.4 8563.2 00:13:20 INFO - PROCESS | 12287 | -------- Summary: end -------- 00:13:20 INFO - PROCESS | 12287 | 00:13:21 INFO - TEST-INFO | 12287: exit 0 00:13:21 INFO - TEST-OK | dromaeo_css | took 1121145ms 00:13:21 INFO - TEST-START | kraken 00:13:21 INFO - operating with platform_type : linux_ 00:13:21 INFO - Initialising browser for kraken test... 00:13:21 INFO - TEST-INFO | started process 12656 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdthVCu/profile http://localhost:55505/getInfo.html) 00:13:24 INFO - PROCESS | 12656 | Iterating over profile /tmp/tmpdthVCu/profile 00:13:25 INFO - PROCESS | 12656 | No birth date. Using mtime. 00:13:25 INFO - PROCESS | 12656 | Using date: /tmp/tmpdthVCu/profile/pluginreg.dat = Mon Feb 20 2017 00:13:24 GMT-0800 (PST) 00:13:25 INFO - PROCESS | 12656 | No birth date. Using mtime. 00:13:25 INFO - PROCESS | 12656 | Using date: /tmp/tmpdthVCu/profile/user.js = Mon Feb 20 2017 00:13:21 GMT-0800 (PST) 00:13:25 INFO - PROCESS | 12656 | No birth date. Using mtime. 00:13:25 INFO - PROCESS | 12656 | Using date: /tmp/tmpdthVCu/profile/crashes = Mon Feb 20 2017 00:13:21 GMT-0800 (PST) 00:13:25 INFO - PROCESS | 12656 | No birth date. Using mtime. 00:13:25 INFO - PROCESS | 12656 | Using date: /tmp/tmpdthVCu/profile/extensions.ini = Mon Feb 20 2017 00:13:22 GMT-0800 (PST) 00:13:25 INFO - PROCESS | 12656 | No birth date. Using mtime. 00:13:25 INFO - PROCESS | 12656 | Using date: /tmp/tmpdthVCu/profile/blocklist.xml = Mon Feb 20 2017 00:13:22 GMT-0800 (PST) 00:13:25 INFO - PROCESS | 12656 | No birth date. Using mtime. 00:13:25 INFO - PROCESS | 12656 | Using date: /tmp/tmpdthVCu/profile/places.sqlite-shm = Mon Feb 20 2017 00:13:23 GMT-0800 (PST) 00:13:26 INFO - PROCESS | 12656 | No birth date. Using mtime. 00:13:26 INFO - PROCESS | 12656 | Using date: /tmp/tmpdthVCu/profile/places.sqlite-wal = Mon Feb 20 2017 00:13:26 GMT-0800 (PST) 00:13:26 INFO - PROCESS | 12656 | No birth date. Using mtime. 00:13:26 INFO - PROCESS | 12656 | Using date: /tmp/tmpdthVCu/profile/cert8.db = Mon Feb 20 2017 00:13:22 GMT-0800 (PST) 00:13:26 INFO - PROCESS | 12656 | No birth date. Using mtime. 00:13:26 INFO - PROCESS | 12656 | Using date: /tmp/tmpdthVCu/profile/startupCache = Mon Feb 20 2017 00:13:21 GMT-0800 (PST) 00:13:26 INFO - PROCESS | 12656 | __metrics Screen width/height:1600/1200 00:13:26 INFO - PROCESS | 12656 | colorDepth:24 00:13:26 INFO - PROCESS | 12656 | Browser inner width/height: 1024/697 00:13:26 INFO - PROCESS | 12656 | __metrics 00:13:26 INFO - PROCESS | 12656 | No birth date. Using mtime. 00:13:26 INFO - PROCESS | 12656 | Using date: /tmp/tmpdthVCu/profile/addons.json = Mon Feb 20 2017 00:13:24 GMT-0800 (PST) 00:13:26 INFO - PROCESS | 12656 | No birth date. Using mtime. 00:13:26 INFO - PROCESS | 12656 | Using date: /tmp/tmpdthVCu/profile/cache2 = Mon Feb 20 2017 00:13:22 GMT-0800 (PST) 00:13:26 INFO - PROCESS | 12656 | No birth date. Using mtime. 00:13:26 INFO - PROCESS | 12656 | Using date: /tmp/tmpdthVCu/profile/bookmarkbackups = Mon Feb 20 2017 00:13:24 GMT-0800 (PST) 00:13:26 INFO - PROCESS | 12656 | No birth date. Using mtime. 00:13:26 INFO - PROCESS | 12656 | Using date: /tmp/tmpdthVCu/profile/gmp = Mon Feb 20 2017 00:13:22 GMT-0800 (PST) 00:13:26 INFO - PROCESS | 12656 | No birth date. Using mtime. 00:13:26 INFO - PROCESS | 12656 | Using date: /tmp/tmpdthVCu/profile/extensions = Mon Feb 20 2017 00:13:22 GMT-0800 (PST) 00:13:26 INFO - PROCESS | 12656 | No birth date. Using mtime. 00:13:26 INFO - PROCESS | 12656 | Using date: /tmp/tmpdthVCu/profile/extensions.json = Mon Feb 20 2017 00:13:24 GMT-0800 (PST) 00:13:26 INFO - PROCESS | 12656 | No birth date. Using mtime. 00:13:26 INFO - PROCESS | 12656 | Using date: /tmp/tmpdthVCu/profile/secmod.db = Mon Feb 20 2017 00:13:22 GMT-0800 (PST) 00:13:26 INFO - PROCESS | 12656 | Stat failure 00:13:26 INFO - PROCESS | 12656 | No birth date. Using mtime. 00:13:26 INFO - PROCESS | 12656 | Using date: /tmp/tmpdthVCu/profile/.parentlock = Mon Feb 20 2017 00:13:21 GMT-0800 (PST) 00:13:26 INFO - PROCESS | 12656 | No birth date. Using mtime. 00:13:26 INFO - PROCESS | 12656 | Using date: /tmp/tmpdthVCu/profile/key3.db = Mon Feb 20 2017 00:13:22 GMT-0800 (PST) 00:13:26 INFO - PROCESS | 12656 | No birth date. Using mtime. 00:13:26 INFO - PROCESS | 12656 | Using date: /tmp/tmpdthVCu/profile/thumbnails = Mon Feb 20 2017 00:13:24 GMT-0800 (PST) 00:13:26 INFO - PROCESS | 12656 | No birth date. Using mtime. 00:13:26 INFO - PROCESS | 12656 | Using date: /tmp/tmpdthVCu/profile/compatibility.ini = Mon Feb 20 2017 00:13:22 GMT-0800 (PST) 00:13:26 INFO - PROCESS | 12656 | No birth date. Using mtime. 00:13:26 INFO - PROCESS | 12656 | Using date: /tmp/tmpdthVCu/profile/frequencyCap.json = Mon Feb 20 2017 00:13:24 GMT-0800 (PST) 00:13:26 INFO - PROCESS | 12656 | No birth date. Using mtime. 00:13:26 INFO - PROCESS | 12656 | Using date: /tmp/tmpdthVCu/profile/minidumps = Mon Feb 20 2017 00:13:21 GMT-0800 (PST) 00:13:26 INFO - PROCESS | 12656 | No birth date. Using mtime. 00:13:26 INFO - PROCESS | 12656 | Using date: /tmp/tmpdthVCu/profile/content-prefs.sqlite = Mon Feb 20 2017 00:13:23 GMT-0800 (PST) 00:13:26 INFO - PROCESS | 12656 | No birth date. Using mtime. 00:13:26 INFO - PROCESS | 12656 | Using date: /tmp/tmpdthVCu/profile/safebrowsing = Mon Feb 20 2017 00:13:26 GMT-0800 (PST) 00:13:26 INFO - PROCESS | 12656 | No birth date. Using mtime. 00:13:26 INFO - PROCESS | 12656 | Using date: /tmp/tmpdthVCu/profile/prefs.js = Mon Feb 20 2017 00:13:26 GMT-0800 (PST) 00:13:26 INFO - PROCESS | 12656 | No birth date. Using mtime. 00:13:26 INFO - PROCESS | 12656 | Using date: /tmp/tmpdthVCu/profile/sessionCheckpoints.json = Mon Feb 20 2017 00:13:25 GMT-0800 (PST) 00:13:26 INFO - PROCESS | 12656 | No birth date. Using mtime. 00:13:26 INFO - PROCESS | 12656 | Using date: /tmp/tmpdthVCu/profile/localstore.rdf = Sun Feb 19 2017 23:53:58 GMT-0800 (PST) 00:13:26 INFO - PROCESS | 12656 | No birth date. Using mtime. 00:13:26 INFO - PROCESS | 12656 | Using date: /tmp/tmpdthVCu/profile/places.sqlite = Mon Feb 20 2017 00:13:26 GMT-0800 (PST) 00:13:26 INFO - PROCESS | 12656 | No birth date. Using mtime. 00:13:26 INFO - PROCESS | 12656 | Using date: /tmp/tmpdthVCu/profile/permissions.sqlite = Mon Feb 20 2017 00:13:22 GMT-0800 (PST) 00:13:26 INFO - PROCESS | 12656 | No birth date. Using mtime. 00:13:26 INFO - PROCESS | 12656 | Using date: /tmp/tmpdthVCu/profile/directoryLinks.json = Mon Feb 20 2017 00:13:24 GMT-0800 (PST) 00:13:27 ERROR - PROCESS | 12656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 00:13:27 INFO - TEST-INFO | 12656: exit 0 00:13:27 INFO - Browser initialized. 00:13:27 INFO - Running cycle 1/1 for kraken test... 00:13:27 INFO - TEST-INFO | started process 12781 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdthVCu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:13:40 INFO - PROCESS | 12781 | RSS: Main: 153591808 00:13:40 INFO - PROCESS | 12781 | 00:13:42 INFO - PROCESS | 12781 | Cycle 1(1): loaded http://localhost:55505/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55505/tests/kraken/driver.html?testName=audio-beat-detection) 00:13:43 INFO - PROCESS | 12781 | RSS: Main: 160227328 00:13:43 INFO - PROCESS | 12781 | 00:13:46 INFO - PROCESS | 12781 | Cycle 1(1): loaded http://localhost:55505/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55505/tests/kraken/driver.html?testName=audio-dft) 00:13:47 INFO - PROCESS | 12781 | RSS: Main: 159404032 00:13:47 INFO - PROCESS | 12781 | 00:13:50 INFO - PROCESS | 12781 | Cycle 1(1): loaded http://localhost:55505/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55505/tests/kraken/driver.html?testName=audio-fft) 00:13:50 INFO - PROCESS | 12781 | RSS: Main: 155406336 00:13:50 INFO - PROCESS | 12781 | 00:13:53 INFO - PROCESS | 12781 | Cycle 1(1): loaded http://localhost:55505/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55505/tests/kraken/driver.html?testName=audio-oscillator) 00:13:53 INFO - PROCESS | 12781 | RSS: Main: 156241920 00:13:53 INFO - PROCESS | 12781 | 00:13:55 INFO - PROCESS | 12781 | Cycle 1(1): loaded http://localhost:55505/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55505/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:13:56 INFO - PROCESS | 12781 | RSS: Main: 156352512 00:13:56 INFO - PROCESS | 12781 | 00:13:59 INFO - PROCESS | 12781 | Cycle 1(1): loaded http://localhost:55505/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55505/tests/kraken/driver.html?testName=imaging-darkroom) 00:14:00 INFO - PROCESS | 12781 | RSS: Main: 161837056 00:14:00 INFO - PROCESS | 12781 | 00:14:03 INFO - PROCESS | 12781 | Cycle 1(1): loaded http://localhost:55505/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55505/tests/kraken/driver.html?testName=imaging-desaturate) 00:14:04 INFO - PROCESS | 12781 | RSS: Main: 158011392 00:14:04 INFO - PROCESS | 12781 | 00:14:07 INFO - PROCESS | 12781 | Cycle 1(1): loaded http://localhost:55505/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55505/tests/kraken/driver.html?testName=json-parse-financial) 00:14:07 INFO - PROCESS | 12781 | RSS: Main: 159031296 00:14:07 INFO - PROCESS | 12781 | 00:14:09 INFO - PROCESS | 12781 | Cycle 1(1): loaded http://localhost:55505/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55505/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:14:10 INFO - PROCESS | 12781 | RSS: Main: 159010816 00:14:10 INFO - PROCESS | 12781 | 00:14:12 INFO - PROCESS | 12781 | Cycle 1(1): loaded http://localhost:55505/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55505/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:14:12 INFO - PROCESS | 12781 | RSS: Main: 158707712 00:14:12 INFO - PROCESS | 12781 | 00:14:14 INFO - PROCESS | 12781 | Cycle 1(1): loaded http://localhost:55505/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55505/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:14:15 INFO - PROCESS | 12781 | RSS: Main: 159612928 00:14:15 INFO - PROCESS | 12781 | 00:14:18 INFO - PROCESS | 12781 | Cycle 1(1): loaded http://localhost:55505/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55505/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:14:19 INFO - PROCESS | 12781 | RSS: Main: 160083968 00:14:19 INFO - PROCESS | 12781 | 00:14:22 INFO - PROCESS | 12781 | Cycle 1(1): loaded http://localhost:55505/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55505/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:14:23 INFO - PROCESS | 12781 | RSS: Main: 162680832 00:14:23 INFO - PROCESS | 12781 | 00:14:24 INFO - PROCESS | 12781 | Cycle 1(1): loaded http://localhost:55505/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55505/tests/kraken/driver.html?testName=ai-astar) 00:14:25 INFO - PROCESS | 12781 | RSS: Main: 163999744 00:14:25 INFO - PROCESS | 12781 | 00:14:25 INFO - PROCESS | 12781 | __start_tp_report 00:14:25 INFO - PROCESS | 12781 | _x_x_mozilla_page_load 00:14:25 INFO - PROCESS | 12781 | _x_x_mozilla_page_load_details 00:14:25 INFO - PROCESS | 12781 | |i|pagename|runs| 00:14:25 INFO - PROCESS | 12781 | |0;ai-astar;108;115;118;116;117;118;117;115;117;116 00:14:25 INFO - PROCESS | 12781 | |1;audio-beat-detection;156;128;126;166;129;127;133;134;153;127 00:14:25 INFO - PROCESS | 12781 | |2;audio-dft;165;167;169;164;168;164;162;168;161;165 00:14:25 INFO - PROCESS | 12781 | |3;audio-fft;76;77;78;111;78;78;77;78;98;78 00:14:25 INFO - PROCESS | 12781 | |4;audio-oscillator;85;84;111;84;84;84;101;85;87;105 00:14:25 INFO - PROCESS | 12781 | |5;imaging-gaussian-blur;114;115;114;115;114;116;114;115;114;114 00:14:25 INFO - PROCESS | 12781 | |6;imaging-darkroom;150;153;151;152;151;149;150;151;150;152 00:14:25 INFO - PROCESS | 12781 | |7;imaging-desaturate;96;96;96;96;96;96;96;97;97;97 00:14:25 INFO - PROCESS | 12781 | |8;json-parse-financial;65;65;65;66;70;66;66;65;66;67 00:14:25 INFO - PROCESS | 12781 | |9;json-stringify-tinderbox;65;66;65;65;65;66;65;65;65;65 00:14:25 INFO - PROCESS | 12781 | |10;stanford-crypto-aes;89;87;91;86;88;88;90;88;87;87 00:14:25 INFO - PROCESS | 12781 | |11;stanford-crypto-ccm;159;139;157;161;158;137;135;145;185;137 00:14:25 INFO - PROCESS | 12781 | |12;stanford-crypto-pbkdf2;184;186;192;183;182;183;190;186;186;185 00:14:25 INFO - PROCESS | 12781 | |13;stanford-crypto-sha256-iterative;72;71;72;73;76;72;71;71;77;75 00:14:25 INFO - PROCESS | 12781 | __end_tp_report 00:14:25 INFO - PROCESS | 12781 | __start_cc_report 00:14:25 INFO - PROCESS | 12781 | _x_x_mozilla_cycle_collect,866 00:14:25 INFO - PROCESS | 12781 | __end_cc_report 00:14:25 INFO - PROCESS | 12781 | __startTimestamp1487578465094__endTimestamp 00:14:25 INFO - PROCESS | 12781 | 00:14:25 INFO - PROCESS | 12781 | ------- Summary: start ------- 00:14:25 INFO - PROCESS | 12781 | Number of tests: 14 00:14:25 INFO - PROCESS | 12781 | 00:14:25 INFO - PROCESS | 12781 | [#0] ai-astar Cycles:10 Average:115.70 Median:117.00 stddev:2.91 (2.5%) stddev-sans-first:1.13 00:14:25 INFO - PROCESS | 12781 | Values: 108.0 115.0 118.0 116.0 117.0 118.0 117.0 115.0 117.0 116.0 00:14:25 INFO - PROCESS | 12781 | 00:14:25 INFO - PROCESS | 12781 | [#1] audio-beat-detection Cycles:10 Average:137.90 Median:133.50 stddev:14.69 (11.0%) stddev-sans-first:14.04 00:14:25 INFO - PROCESS | 12781 | Values: 156.0 128.0 126.0 166.0 129.0 127.0 133.0 134.0 153.0 127.0 00:14:25 INFO - PROCESS | 12781 | 00:14:25 INFO - PROCESS | 12781 | [#2] audio-dft Cycles:10 Average:165.30 Median:166.00 stddev:2.67 (1.6%) stddev-sans-first:2.83 00:14:25 INFO - PROCESS | 12781 | Values: 165.0 167.0 169.0 164.0 168.0 164.0 162.0 168.0 161.0 165.0 00:14:25 INFO - PROCESS | 12781 | 00:14:25 INFO - PROCESS | 12781 | [#3] audio-fft Cycles:10 Average:82.90 Median:78.00 stddev:11.81 (15.1%) stddev-sans-first:12.26 00:14:25 INFO - PROCESS | 12781 | Values: 76.0 77.0 78.0 111.0 78.0 78.0 77.0 78.0 98.0 78.0 00:14:25 INFO - PROCESS | 12781 | 00:14:25 INFO - PROCESS | 12781 | [#4] audio-oscillator Cycles:10 Average:91.00 Median:84.00 stddev:10.43 (12.4%) stddev-sans-first:10.84 00:14:25 INFO - PROCESS | 12781 | Values: 85.0 84.0 111.0 84.0 84.0 84.0 101.0 85.0 87.0 105.0 00:14:25 INFO - PROCESS | 12781 | 00:14:25 INFO - PROCESS | 12781 | [#5] imaging-gaussian-blur Cycles:10 Average:114.50 Median:114.50 stddev:0.71 (0.6%) stddev-sans-first:0.73 00:14:25 INFO - PROCESS | 12781 | Values: 114.0 115.0 114.0 115.0 114.0 116.0 114.0 115.0 114.0 114.0 00:14:25 INFO - PROCESS | 12781 | 00:14:25 INFO - PROCESS | 12781 | [#6] imaging-darkroom Cycles:10 Average:150.90 Median:151.00 stddev:1.20 (0.8%) stddev-sans-first:1.22 00:14:25 INFO - PROCESS | 12781 | Values: 150.0 153.0 151.0 152.0 151.0 149.0 150.0 151.0 150.0 152.0 00:14:25 INFO - PROCESS | 12781 | 00:14:25 INFO - PROCESS | 12781 | [#7] imaging-desaturate Cycles:10 Average:96.30 Median:96.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 00:14:25 INFO - PROCESS | 12781 | Values: 96.0 96.0 96.0 96.0 96.0 96.0 96.0 97.0 97.0 97.0 00:14:25 INFO - PROCESS | 12781 | 00:14:25 INFO - PROCESS | 12781 | [#8] json-parse-financial Cycles:10 Average:66.10 Median:66.00 stddev:1.52 (2.3%) stddev-sans-first:1.56 00:14:25 INFO - PROCESS | 12781 | Values: 65.0 65.0 65.0 66.0 70.0 66.0 66.0 65.0 66.0 67.0 00:14:25 INFO - PROCESS | 12781 | 00:14:25 INFO - PROCESS | 12781 | [#9] json-stringify-tinderbox Cycles:10 Average:65.20 Median:65.00 stddev:0.42 (0.6%) stddev-sans-first:0.44 00:14:25 INFO - PROCESS | 12781 | Values: 65.0 66.0 65.0 65.0 65.0 66.0 65.0 65.0 65.0 65.0 00:14:25 INFO - PROCESS | 12781 | 00:14:25 INFO - PROCESS | 12781 | [#10] stanford-crypto-aes Cycles:10 Average:88.10 Median:88.00 stddev:1.52 (1.7%) stddev-sans-first:1.58 00:14:25 INFO - PROCESS | 12781 | Values: 89.0 87.0 91.0 86.0 88.0 88.0 90.0 88.0 87.0 87.0 00:14:25 INFO - PROCESS | 12781 | 00:14:25 INFO - PROCESS | 12781 | [#11] stanford-crypto-ccm Cycles:10 Average:151.30 Median:157.50 stddev:15.75 (10.0%) stddev-sans-first:16.46 00:14:25 INFO - PROCESS | 12781 | Values: 159.0 139.0 157.0 161.0 158.0 137.0 135.0 145.0 185.0 137.0 00:14:25 INFO - PROCESS | 12781 | 00:14:25 INFO - PROCESS | 12781 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.70 Median:186.00 stddev:3.16 (1.7%) stddev-sans-first:3.30 00:14:25 INFO - PROCESS | 12781 | Values: 184.0 186.0 192.0 183.0 182.0 183.0 190.0 186.0 186.0 185.0 00:14:25 INFO - PROCESS | 12781 | 00:14:25 INFO - PROCESS | 12781 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.00 Median:72.50 stddev:2.21 (3.0%) stddev-sans-first:2.32 00:14:25 INFO - PROCESS | 12781 | Values: 72.0 71.0 72.0 73.0 76.0 72.0 71.0 71.0 77.0 75.0 00:14:25 INFO - PROCESS | 12781 | -------- Summary: end -------- 00:14:25 INFO - PROCESS | 12781 | 00:14:26 INFO - TEST-INFO | 12781: exit 0 00:14:26 INFO - TEST-OK | kraken | took 64425ms 00:14:26 INFO - SUITE-END | took 1185s 00:14:26 INFO - Completed test suite (00:19:45) 00:14:26 INFO - javascript benchmark 00:14:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6250.798827729936, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2572.794176465883, 2572.8199026068182, 2586.780123689486, 2606.973930260698, 2608.0, 4709.976450117749, 4757.1437141314545, 4758.6906851054655, 4766.475687674358, 4873.366462359888, 4854.199057155565, 4890.73100979446, 4947.158982972895, 4950.405951285851, 4992.276119962603, 4216.662666986634, 4502.122086193193, 4586.587207151356, 4607.677462577621, 4609.124266389392, 4598.563136502027, 4606.286025666027, 4606.723596584216, 4609.44686637604, 4611.216093264153, 4403.691741578091, 4434.645228381754, 4452.666050046259, 4503.211937910853, 4519.050999290153, 4466.709663871838, 4466.7766611669285, 4474.6420286377015, 4530.116627257686, 4535.569120933539, 4416.116776644684, 4447.7776111194635, 4451.955480445186, 4473.843415480443, 4496.820127194908, 4768.451628062782, 4808.591269742076, 4872.952315252202, 4891.070696567641, 4894.437139728905, 5150.587952963754, 5187.792488300463, 5189.325387699612, 5204.4535323791215, 5227.477252274742, 5208.427073021964, 5221.791128354861, 5228.79084836606, 5236.293100431431, 5272.551833094191, 5008.348914641073, 5008.874778130576, 5012.649114561982, 5020.497950205024, 5033.345665063518, 5226.738663066832, 5281.445448227958, 5332.573394128461, 5333.466653334713, 5343.492368225013, 5136.075506408883, 5137.614678899097, 5154.896902061938, 5167.948320516822, 5201.895962080737, 5202.895942081137, 5241.475852414728, 5258.2112683097075, 5263.184206448007, 5309.734513274321, 5063.518965698253, 5128.076946149654, 5176.456472070378, 5188.22176673502, 5213.530782229617, 5231.843044708665, 5242.868928276747, 5243.370795504564, 5255.947440525622, 5257.8159764408065, 4512.300593408027, 4521.073179998159, 4524.208263553931, 4530.252508336088, 4568.748718820497, 3856.787876666754, 3891.0272431892026, 3907.980460097738, 3930.4104384342577, 3952.071263253133, 4495.797689103998, 4523.796429160696, 4544.636429085665, 4553.134904368159, 4603.6777425579885, 5234.18870075139, 5240.6069544784295, 5244.108501554745, 5269.130593452114, 5272.024675435056, 4592.058627981256, 4593.28804035375, 4594.609458196024, 4597.448306203277, 4610.515895830889, 4644.60520855731, 4684.189486308256, 4696.154692155376, 4696.225122854691, 4737.478877323491, 4860.222364421676, 4882.658213924991, 4882.998985208095, 4896.290037944549, 4903.215485522299, 5242.816501422432, 5244.213367994831, 5257.842264732064, 5259.947400525945, 5274.815381461631, 4935.0, 4943.802247910044, 4945.357103576512, 4951.554360107608, 4971.1797553403285, 5163.225516172604, 5210.8957820844125, 5220.321358223407, 5236.136037553761, 5246.632735708464, 5053.0, 5097.6941383517105, 5142.897142057063, 5198.454162312903, 5289.656172348785, 5045.445001049955, 5050.77271522775, 5058.822941197041, 5065.518775716301, 5084.720340381315, 5046.19260918266, 5056.84829455117, 5058.620603454682, 5067.138586440387, 5102.69383836971, 4901.583365413837, 4922.581580565692, 4958.181899986462, 4974.527419895104, 5034.345535080416, 5161.277421161116, 5220.060389129993, 5225.216217567396, 5260.658057226421, 5276.683398996072, 5060.240963855451, 5087.262346959744, 5092.694438333712, 5114.539691427789, 5126.0], "value": 4801.753575552751, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [509.9209622508511, 524.716653007376, 544.3060098374572, 587.6767777722251, 587.8412828536293, 33658.83170584147, 37123.443148352795, 37238.441423378674, 37263.0, 37265.441018384714, 36846.26307473849, 37568.81215593925, 37640.43539346912, 37653.81173094137, 37754.8112259439, 7421.554706717588, 7667.731629392944, 9069.410488318272, 9493.382930109556, 9503.904960950405, 9352.766180845465, 9427.622895084189, 9430.198433133157, 9446.055394460533, 9451.763705907339, 9333.253339732804, 9421.48181849998, 9436.669716559922, 9438.858417123714, 9469.621215151386, 9027.232685221765, 9069.0, 9072.274218062572, 9086.227670648002, 9109.498977556232, 8924.687635932776, 8962.910370896338, 8984.955075224647, 8988.820223595558, 9045.68340108093, 32559.51160732591, 32807.50788738171, 32874.6712532874, 32944.17639559006, 33073.83463082693, 3142.905712828596, 3159.1312389092955, 3205.983970080158, 3247.171971147366, 3280.3439312137666, 24286.757132428625, 24649.753502465104, 25004.624930626054, 25030.749692503025, 25329.078183408797, 3212.293295475015, 3229.612446506411, 3238.951415728766, 3259.5273685315733, 3275.901722948315, 217709.0, 217821.0, 218384.0, 219049.0, 219387.0, 38244.23511529754, 39411.60588394137, 44581.33128003083, 45850.77074614605, 46131.76934115375, 48714.0, 48884.0, 49079.0, 49242.507574923795, 49298.0, 11186.888131118585, 11246.606368777053, 11299.830502542469, 11329.206955513035, 11361.375124368074, 9222.76943076415, 9288.0, 9338.859917101248, 9422.90577094234, 9431.245500359955, 15345.925785195153, 15388.923055384652, 15426.537203883901, 15463.768043479356, 15574.0, 6474.971035915431, 6506.739730410825, 6579.30917253691, 6657.367550082734, 6801.829954251183, 741.3142842870324, 748.3863232149694, 756.2324240895496, 764.6176911544228, 765.6822418696179, 24550.263492095266, 25871.353216169748, 25901.352466188495, 26046.869765651052, 26463.735362646126, 919.5126582911067, 935.3592788939462, 942.9198518125899, 951.3293128344501, 962.0667952086465, 12936.223826570436, 13934.225579385404, 13994.790078148631, 14156.0, 14195.503157389441, 293102.0, 294951.0, 295534.0, 296399.0, 297540.0, 47418.52581474141, 47470.76264618655, 47747.52252477431, 47778.0, 47918.0, 1158.2992289664633, 1169.8830116988404, 1207.5954555224098, 1211.5820042085466, 1215.4166000319897, 23687.289381318587, 24212.878935605208, 25353.619695704947, 25821.225363239133, 27520.58719119255, 39936.4009539863, 39950.2009959805, 40260.0, 40383.3942490857, 40388.394174087996, 280613.0, 283552.0, 285024.0, 285629.0, 285700.0, 22449.0, 22737.0, 23311.41721456977, 23374.532509349378, 24063.157789477285, 35289.47065793964, 35405.46891796677, 35503.0, 35786.0, 36265.456018159224, 30504.237394065327, 30581.388372231984, 30790.538141928337, 31433.528497072104, 31800.25098619598, 29395.559066614445, 29768.553471698375, 29781.553276700433, 30246.395072098876, 30258.0, 30754.077377678706, 30991.380172395973, 31078.533821992234, 31121.8443907779, 31140.53289200709, 25964.792992595085, 27044.45911081807, 27815.582766258118, 27834.0, 27899.16302510928, 21554.461138471663, 21760.238391656218, 21820.781792181875, 21879.453013674785, 22168.445788855406], "value": 16197.069626173177, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.8708439130695, 390.9257241124186, 393.2351576184321, 404.7065877239004, 415.93760935859615, 15637.765433518493, 17494.825051749478, 17564.648707025866, 17669.46991590251, 17677.646447071067, 16738.66522669546, 17846.553836154104, 17932.462026139205, 18147.092645367713, 18384.908075459636, 5129.2562578426105, 5342.0, 5345.1714984177515, 5345.33183352081, 5353.866153346178, 5220.6606570572985, 5464.371597266326, 5493.478119578654, 5546.500814926656, 5596.076647353182, 5465.59008074394, 5553.666779993193, 5570.275864137657, 5570.498655121059, 5575.107982722765, 6223.4398904098625, 6235.875282494348, 6260.092286618415, 6264.9686751566405, 6272.435480806704, 5318.494742999409, 5447.509724124848, 5492.093804522249, 5557.5831812614215, 5580.581456390787, 21118.577628447347, 21236.30109591229, 21395.572088558143, 21604.243851465122, 21773.129074836987, 2015.0932080563805, 2136.0921608316403, 2368.6447032945025, 2504.5742223821994, 2510.2469259222344, 9109.180173784325, 9560.904390956071, 9620.71137865865, 9900.752481188028, 9966.252531060127, 1623.7645541396432, 1657.991710041454, 1662.8752843536784, 1697.1599058466015, 1707.6670049340385, 69048.30951690419, 69335.0, 70862.64568677175, 71508.64245678738, 74686.62656686736, 10743.946280268705, 10814.945925270324, 10837.133029357632, 10999.560017599206, 11052.447377631086, 15073.095614263179, 15100.697986040217, 15107.54677359681, 15111.093334399971, 15237.619059523378, 6597.60414375139, 6641.203055633355, 6826.385625293648, 6836.38472537474, 6878.27778083294, 5964.1650168976375, 5964.612300200473, 6083.0, 6102.572819902653, 6107.419795119546, 4493.910331695777, 4666.32338310943, 4691.366665500113, 4741.454732705714, 4854.0, 1950.7854136045023, 1993.641144593958, 2014.6071516054521, 2016.2136766661015, 2021.9292324768562, 773.1881524399358, 790.632355954484, 804.8591496488208, 822.7655118291378, 829.1749709039544, 16851.241694123793, 17014.659706806044, 17364.0, 17475.0, 17959.281628734967, 790.3361176611673, 798.4171554765054, 804.1074407407773, 810.8337790752881, 810.8864758933754, 9269.304802139866, 9703.66037188695, 9731.124198821999, 9745.317827752131, 9757.560909759079, 326327.0, 328886.0, 330956.0, 482531.0, 486762.0, 69637.0, 83629.0, 85250.5737471321, 91258.0, 92486.83809228601, 932.6922115701938, 967.1537404770826, 984.2175470500969, 984.3945973226414, 985.531872360623, 15421.0, 15659.843401565839, 16366.75449868229, 16420.589485262488, 16689.0, 36989.630103699696, 38186.0, 38288.0, 38342.616573833904, 38557.807210963765, 47736.5226347746, 50655.98688026293, 52963.0, 53318.0, 53449.73275133599, 7895.210478952059, 7986.480878742863, 8007.079185893464, 8039.598020098854, 8050.718224862102, 10940.39827809478, 11506.769864602493, 11549.0, 11761.411929403666, 12140.575079872162, 10139.290249682746, 10304.845427318447, 10470.424126673106, 10584.682459526226, 10622.8406573903, 10422.270441069355, 10633.989770971746, 10798.460076996276, 10838.295510791773, 11061.889381106086, 9746.074122958307, 10338.379697218192, 10500.317479363817, 10689.786204275715, 11174.608888689181, 7529.962350188214, 7599.088109426904, 7647.464677472523, 7932.881006785018, 7991.120976692443, 9197.862032069657, 9208.677696280598, 9329.253659707343, 9346.953265233631, 9610.0], "value": 8422.597664021641, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1352.9391177397017, 1357.4570171931227, 1360.387825478535, 1366.52854765106, 1372.9794053089201, 7221.458390620704, 7476.140243871956, 7671.7698469045945, 7692.0384951881015, 7705.306522412981, 7648.158702542716, 7748.922510774891, 7758.301752842243, 7763.06843178818, 7780.416468764837, 2039.6838490033995, 2054.1577953039255, 2066.803653652908, 2069.5550456651818, 2071.0576687607104, 1972.5265936175358, 2024.9797502025012, 2044.1194101426722, 2049.9282525111626, 2051.4358551398336, 2010.4270282969358, 2030.5735795482967, 2033.7356143701372, 2045.9590808183827, 2048.2114385961436, 2058.557410156816, 2071.886046267462, 2072.823809976154, 2081.2819577245828, 2088.2691058129535, 1763.902985335806, 1817.9909100454413, 2020.9696854547194, 2029.6346657601625, 2049.477383267257, 7273.6363181841225, 7501.6999320027135, 7513.474056816025, 7682.846343073164, 7699.576523291216, 4389.561043895585, 4511.729296242236, 4522.7286362818, 4530.6602004849765, 4538.02432477019, 2697.1234348836706, 2762.613234147221, 2824.9011284605144, 2826.547752359613, 2827.618271533337, 1061.4427425601543, 1074.2319241742114, 1086.5165001574303, 1100.4703462187565, 1160.9187356885022, 9962.302638815287, 9981.30130890838, 10042.69871903844, 10068.194544436428, 10094.49527523621, 3628.32875917956, 3717.2565486902727, 3738.36447803874, 3790.886273411802, 3860.4402361657403, 8174.67301307941, 8181.631826567819, 8193.0, 8193.385496087818, 8206.93309869713, 4267.658587313008, 4298.914021719548, 4319.654427645781, 4324.329728892027, 4353.303471444554, 3855.190410013872, 4004.8598299059977, 4014.4781178446615, 4037.0714735610386, 4048.5748996355546, 3221.259110404573, 3261.445554255782, 3320.601527816629, 3322.103032181346, 3358.177246574556, 953.1374106433689, 955.6511873166384, 963.6193703487223, 968.5157421289356, 974.8927617962019, 736.1423941108549, 770.1335997003371, 783.5063909736944, 784.5136015670321, 787.1066339704477, 4799.856004319876, 4874.634402419832, 4892.486288939681, 4904.754762261872, 5036.294918711384, 844.5059640110481, 873.8689196620558, 874.9125087491327, 878.5343767803074, 893.6246776353947, 3164.4620414529586, 3232.7413806895393, 3350.480675495302, 3355.563776709012, 3458.9827050864583, 7976.242256985577, 8313.08556058833, 8586.09845966177, 8777.42946708462, 9264.44413335202, 8780.736577902673, 9084.772880677903, 9124.087591240823, 9127.954360228156, 9147.0852914708, 6888.207856096513, 7008.0, 7227.747028853965, 7252.818679532948, 7308.232635573295, 4945.455999839943, 5037.722925239147, 5075.086484432837, 5107.744612769272, 5157.535821776059, 7808.141104478616, 8065.112837587978, 8155.510669359857, 8209.096999330188, 8210.753677389688, 8160.30637395828, 8289.502629842229, 8382.0, 8388.161183881808, 8573.442726222776, 6012.969935150296, 6183.84540386494, 6193.876122477615, 6207.689615519115, 6217.222847144107, 7099.07711997437, 7123.608201548965, 7147.213806481179, 7197.0, 7213.0, 6507.544471887112, 6522.673866306571, 6524.967375163284, 6526.869462610816, 6561.114249576246, 6318.62088274705, 6412.422881940648, 6438.645874476949, 6599.208095028627, 6610.10763546915, 5516.062269414289, 5538.252335934597, 5571.0, 5575.776968921197, 5583.330000399978, 4275.273203555465, 4330.090680957034, 4412.404325416028, 4428.468583769839, 4497.3928519650735, 4117.21772863155, 4129.793510324532, 4272.124214536075, 4311.8490852820005, 4348.565143485753], "value": 3900.381809573195, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.2730148118954, 339.9133221028638, 345.51282691405123, 346.3436787288089, 348.27906234095417, 5097.515736005078, 5173.663711858736, 5178.326817513718, 5182.611304152189, 5183.352080989872, 5049.596032317415, 5055.393352797661, 5062.924056139161, 5118.744062796854, 5138.871528211796, 3788.2802267569214, 3896.3960586109197, 3914.9804250978705, 3931.429942658327, 4096.631303182713, 3922.745021573603, 3929.9803500982457, 3934.131287638363, 3938.5230538548044, 3942.231264903345, 3884.76691398517, 3900.5904380040115, 3901.161250331178, 3902.7072969527258, 3915.588863169369, 3708.7774733516076, 3907.6678482328894, 3908.9804550977346, 3910.667593254577, 3910.8240129194255, 3417.3165366926464, 3443.500692399613, 3447.5862896452586, 3447.931041379158, 3450.861965521376, 4920.089783390084, 4946.653734238605, 4952.182889823175, 4952.207646776535, 4982.352294201767, 4480.798364073625, 4489.079738653568, 4492.550744925481, 4494.5730155635165, 4506.408814931395, 5204.9479505204845, 5259.316288882459, 5271.393789714193, 5289.814856480005, 5358.91961620576, 3697.0018095114438, 3705.7220708446703, 3710.495415594463, 3714.182879766447, 3718.5909549949483, 5254.658447200958, 5527.944720552823, 5548.834744703617, 5569.220309156722, 5586.385497595261, 3579.7673151245085, 3681.392570225936, 3785.507883975121, 3817.904552386157, 3827.234553089393, 3816.637419445148, 3854.8650797221962, 3858.5369755629504, 3866.516685414323, 3866.883993480144, 4438.201123797683, 4445.444319460067, 4447.0, 4450.1989641864775, 4458.328126952393, 5103.515166059218, 5314.70769107695, 5322.201669749568, 5339.318114793905, 5341.439148889388, 1248.2759999200441, 1254.7804134276648, 1254.9646541603213, 1255.918365306252, 1257.6289994451658, 811.9918800811917, 833.4041160570222, 847.8092429203504, 856.1609622569792, 858.5621333120029, 4539.501964351756, 4542.84100056503, 4549.818007279738, 4550.5449455054895, 4555.3850230218495, 5238.109521381374, 5302.92045619316, 5316.893662126736, 5319.202119682079, 5345.572354211654, 4291.313389857608, 4511.639068874482, 4523.479799823062, 4538.523455037173, 4539.296409056601, 2811.620431241797, 2824.717528247159, 2828.476731804623, 2835.6738975017724, 2840.218939791574, 4975.701457912537, 4986.626003049786, 4999.375078115236, 5005.721252579566, 5009.0, 5107.361579802525, 5389.892202156013, 5444.005796811738, 5453.781848726086, 5489.204065410573, 4960.8759781005765, 4975.825846095369, 4979.90040199194, 5001.699898006132, 5008.373953255843, 5261.185640410672, 5290.682559046316, 5300.522952934254, 5305.416404195613, 5308.708021058879, 5073.441921388718, 5180.9740951295, 5187.922181167361, 5422.62041657096, 5456.809011684731, 5015.448300686849, 5168.431472537943, 5177.980587570925, 5180.145276029413, 5181.5077567631015, 3758.6993040557236, 3783.754055986352, 3801.8099095044586, 3811.5997820228476, 3836.7506112102624, 5482.725863706719, 5489.4236105209975, 5490.560755139659, 5499.862503437446, 5502.477264659851, 5071.366079240095, 5319.813806516908, 5333.359996800409, 5350.5184533392185, 5361.651492652965, 5287.471252874681, 5300.7879684813715, 5304.867378315573, 5313.7682173488, 5328.49379308965, 5272.815451459181, 5279.313689220377, 5290.153575427913, 5300.496452836975, 5329.120695085423, 5210.27056212123, 5328.973355133199, 5384.896096300327, 5386.649867758583, 5417.404085550507, 5243.737813109253, 5257.868553286045, 5259.395169555397, 5260.710660913686, 5270.710110943782], "value": 3955.279315759754, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [627.7175271127993, 633.8003528888403, 706.5230969095859, 722.6242353975932, 741.1921006103346, 11927.10546708997, 12491.500339986413, 12641.367931603429, 12753.681157971032, 12766.170198937063, 12946.352682365867, 12952.417141228643, 13043.478260869553, 13068.346582670876, 13097.214167149978, 812.086402796855, 826.5412695953743, 830.0537387378394, 830.4975489828657, 831.4927893984665, 806.5080301016404, 828.7306625346756, 848.6308455821707, 849.5157760076759, 850.4897061762923, 861.4615865084322, 865.9307255419551, 866.0127454701645, 866.2290561400358, 866.6230189867427, 872.5070335260557, 872.5811610426999, 872.8297981893534, 872.9301655867547, 873.6330741088694, 633.8700566383881, 634.4702173684979, 636.6275728698716, 637.5409705012383, 641.2113100885937, 12429.627111186679, 12792.808107878389, 12800.743985120338, 12808.679783005406, 14100.435982560686, 12449.439775210138, 12889.226646401246, 12924.612261632072, 12978.935105324508, 13252.602421927357, 10341.534630941625, 10423.583056677724, 10551.73620659482, 10555.091882274779, 10696.625618103408, 3150.3384289299274, 3173.1908363367424, 3265.102096923365, 3308.801471911669, 3312.7018568328967, 59242.70378648122, 60646.0, 60931.69534152345, 61358.69320653413, 61645.0, 9538.761530961643, 9596.95201523988, 9637.662681806103, 9837.655682051094, 9866.260030497742, 18316.63366732658, 18319.725204121947, 18371.448856534324, 18394.356197533023, 18445.5388615283, 5990.9101363479585, 6006.759729610767, 6256.0, 6267.091271765659, 6293.496520278367, 3391.9491207631904, 3476.203023779551, 3513.508108864761, 3530.1880567469625, 3547.5388199534414, 2810.3255218747354, 2867.240181351943, 2882.610847535556, 2887.797854150231, 2906.3170155013554, 453.75950746104616, 459.2582978489782, 469.51170782385935, 471.69104236724314, 472.2302646685949, 578.8234588450563, 613.6072913335469, 614.3365165621118, 622.1973653986307, 632.601461079517, 8065.6908816985115, 8384.958075209584, 8397.580120993925, 8401.453905496246, 8610.612522436506, 450.56970593083526, 482.312704396234, 483.47784392856335, 489.6155721635586, 522.1671434062664, 3697.2859604005107, 4019.517657881073, 4132.2561938850695, 4150.418941348214, 4167.437395951569, 54646.72676636671, 57418.0, 58892.70553647204, 58897.411025889196, 59494.70252648709, 51840.0, 52355.0, 52778.0, 52823.0, 53257.46742532525, 44743.32885006727, 45175.09649807051, 45312.32031519596, 45859.0, 46612.3008154878, 7686.730964416219, 7964.9203507966495, 8037.397195210266, 8053.516788992679, 8103.635336409993, 31523.21191970219, 31805.840970794998, 32180.0, 32730.0, 33619.49570756392, 38128.428073579475, 39881.2023759529, 40273.798631006655, 40432.59567404288, 41907.7904610475, 9082.772930676785, 9282.396644218104, 9313.906860931304, 9448.763780905532, 9469.66856160031, 11032.0, 11122.221444498808, 11283.435828208392, 11286.266392684449, 11468.770624587629, 9706.126448619376, 9765.804683906425, 9859.556319965475, 9876.259280553844, 9959.05388988045, 9172.495512746864, 9182.449053056838, 9190.448573085636, 9286.860697089414, 9368.156865882103, 9522.291407222914, 9531.618735250513, 9583.472908990072, 9890.653827116017, 10100.898991009995, 7488.7004519820775, 7687.730929417444, 7874.803129921798, 7888.921110788819, 7920.801979950547, 7883.821564600057, 8006.834737252167, 8202.302804261704, 8536.44513106646, 8563.186497282748], "value": 5902.008209402909, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1583.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 115.0, 118.0, 116.0, 117.0, 118.0, 117.0, 115.0, 117.0, 116.0], "value": 115.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 128.0, 126.0, 166.0, 129.0, 127.0, 133.0, 134.0, 153.0, 127.0], "value": 137.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 167.0, 169.0, 164.0, 168.0, 164.0, 162.0, 168.0, 161.0, 165.0], "value": 165.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 78.0, 111.0, 78.0, 78.0, 77.0, 78.0, 98.0, 78.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 111.0, 84.0, 84.0, 84.0, 101.0, 85.0, 87.0, 105.0], "value": 91.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 114.0, 115.0, 114.0, 116.0, 114.0, 115.0, 114.0, 114.0], "value": 114.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 153.0, 151.0, 152.0, 151.0, 149.0, 150.0, 151.0, 150.0, 152.0], "value": 150.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 97.0, 97.0, 97.0], "value": 96.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 66.0, 70.0, 66.0, 66.0, 65.0, 66.0, 67.0], "value": 66.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 65.0, 65.0, 66.0, 65.0, 65.0, 65.0, 65.0], "value": 65.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 91.0, 86.0, 88.0, 88.0, 90.0, 88.0, 87.0, 87.0], "value": 88.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 139.0, 157.0, 161.0, 158.0, 137.0, 135.0, 145.0, 185.0, 137.0], "value": 151.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 192.0, 183.0, 182.0, 183.0, 190.0, 186.0, 186.0, 185.0], "value": 185.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 71.0, 72.0, 73.0, 76.0, 72.0, 71.0, 71.0, 77.0, 75.0], "value": 73.0, "unit": "score"}]}]} 00:14:26 INFO - Return code: 0 00:14:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:14:26 ERROR - # TBPL SUCCESS # 00:14:26 INFO - Running post-action listener: _resource_record_post_action 00:14:26 INFO - [mozharness: 2017-02-20 08:14:26.404241Z] Finished run-tests step (success) 00:14:26 INFO - Running post-run listener: _resource_record_post_run 00:14:26 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 8192; Write bytes: 361914368; Read time: 104; Write time: 213452 00:14:26 INFO - TinderboxPrint: CPU usage
13.5% 00:14:26 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 104 00:14:26 INFO - TinderboxPrint: I/O write bytes / time
361,914,368 / 213,452 00:14:26 INFO - TinderboxPrint: CPU idle
8,292.1 (86.5%) 00:14:26 INFO - TinderboxPrint: CPU user
1,234.9 (12.9%) 00:14:26 INFO - TinderboxPrint: Swap in / out
0 / 0 00:14:26 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 105062400; Read time: 0; Write time: 68220 00:14:26 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 8192; Write bytes: 256155648; Read time: 104; Write time: 145232 00:14:27 INFO - Running post-run listener: _upload_blobber_files 00:14:27 INFO - Blob upload gear active. 00:14:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:14:27 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 00:14:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 00:14:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 00:14:27 INFO - (blobuploader) - INFO - Open directory for files ... 00:14:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 00:14:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:14:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:14:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 00:14:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:14:28 INFO - (blobuploader) - INFO - Done attempting. 00:14:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:14:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:14:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:14:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:14:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:14:31 INFO - (blobuploader) - INFO - Done attempting. 00:14:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 00:14:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:14:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:14:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 00:14:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:14:32 INFO - (blobuploader) - INFO - Done attempting. 00:14:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 00:14:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:14:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:14:33 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 00:14:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:14:33 INFO - (blobuploader) - INFO - Done attempting. 00:14:33 INFO - (blobuploader) - INFO - Iteration through files over. 00:14:33 INFO - Return code: 0 00:14:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:14:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:14:33 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/27f28ca0d84e5cad5a5f3339abb1b7b445430e5578cf8a3574410d8a1e77b562bc9667db6267a8fb7f8e02b1c9419051df64e74e7b518cd89a95cdf8ca3ea981", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/29389770f954e1465ff81639e0106aa206bda105b4f91fd8092010671f64c90cde4fc9c5c417403a315418c599e0b8ce3e4d1d79f284916ab0038ff17a2cebff", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/93989de87abe3ab07a57c1d4a02c625f577ece57d0390d91b3e9581e9619fe88574532bc7da70b1a941fa162431c2b48640cd01a5c984e06d2801fe1e3e13bef", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/034c3be0712905c7a2ecd994fb924b960efd9ce345d01102fc28167787ea4935f480bc01177a009e970e39805df1c506240641dee0470f43b520d69007b32ae5"} 00:14:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:14:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:14:33 INFO - Contents: 00:14:33 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/27f28ca0d84e5cad5a5f3339abb1b7b445430e5578cf8a3574410d8a1e77b562bc9667db6267a8fb7f8e02b1c9419051df64e74e7b518cd89a95cdf8ca3ea981", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/29389770f954e1465ff81639e0106aa206bda105b4f91fd8092010671f64c90cde4fc9c5c417403a315418c599e0b8ce3e4d1d79f284916ab0038ff17a2cebff", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/93989de87abe3ab07a57c1d4a02c625f577ece57d0390d91b3e9581e9619fe88574532bc7da70b1a941fa162431c2b48640cd01a5c984e06d2801fe1e3e13bef", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/034c3be0712905c7a2ecd994fb924b960efd9ce345d01102fc28167787ea4935f480bc01177a009e970e39805df1c506240641dee0470f43b520d69007b32ae5"} 00:14:33 INFO - Running post-run listener: copy_logs_to_upload_dir 00:14:33 INFO - Copying logs to upload dir... 00:14:33 INFO - mkdir: /builds/slave/test/build/upload/logs 00:14:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.076698 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-20 00:14:33.602545) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-20 00:14:33.607908) ========= 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=db0483c52d987ecb441a263b000001ad-1487576936.359303-1904231953 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/27f28ca0d84e5cad5a5f3339abb1b7b445430e5578cf8a3574410d8a1e77b562bc9667db6267a8fb7f8e02b1c9419051df64e74e7b518cd89a95cdf8ca3ea981", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/29389770f954e1465ff81639e0106aa206bda105b4f91fd8092010671f64c90cde4fc9c5c417403a315418c599e0b8ce3e4d1d79f284916ab0038ff17a2cebff", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/93989de87abe3ab07a57c1d4a02c625f577ece57d0390d91b3e9581e9619fe88574532bc7da70b1a941fa162431c2b48640cd01a5c984e06d2801fe1e3e13bef", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/034c3be0712905c7a2ecd994fb924b960efd9ce345d01102fc28167787ea4935f480bc01177a009e970e39805df1c506240641dee0470f43b520d69007b32ae5"} build_url:https://queue.taskcluster.net/v1/task/BYB1M964StmeU_0sm8ursg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011435 build_url: 'https://queue.taskcluster.net/v1/task/BYB1M964StmeU_0sm8ursg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/27f28ca0d84e5cad5a5f3339abb1b7b445430e5578cf8a3574410d8a1e77b562bc9667db6267a8fb7f8e02b1c9419051df64e74e7b518cd89a95cdf8ca3ea981", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/29389770f954e1465ff81639e0106aa206bda105b4f91fd8092010671f64c90cde4fc9c5c417403a315418c599e0b8ce3e4d1d79f284916ab0038ff17a2cebff", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/93989de87abe3ab07a57c1d4a02c625f577ece57d0390d91b3e9581e9619fe88574532bc7da70b1a941fa162431c2b48640cd01a5c984e06d2801fe1e3e13bef", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/034c3be0712905c7a2ecd994fb924b960efd9ce345d01102fc28167787ea4935f480bc01177a009e970e39805df1c506240641dee0470f43b520d69007b32ae5"}' ========= master_lag: 0.26 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-20 00:14:33.875202) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 00:14:33.875536) ========= 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=db0483c52d987ecb441a263b000001ad-1487576936.359303-1904231953 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004489 ========= master_lag: 0.28 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 00:14:34.163689) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-20 00:14:34.163984) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-20 00:14:34.164277) ========= ========= Total master_lag: 0.67 =========